0000582: VOD playing failure - CNS-VaaS Bug Tracker

ID	Project	Category	View Status	Date Submitted	Last Update

0000582	T99X171.00 SKB Eagle_Vendor_Issue	Player Issue	public	2019-03-25 16:56	2019-06-18 19:01

Reporter	(ALTech) Jewoo Lee	Assigned To	(ALTech) Jewoo Lee	Due Date	2019-03-29 17:46
Priority	high	Severity	s2-severe	Reproducibility	sometimes
Status	closed	Resolution	fixed

Summary	0000582: VOD playing failure
Description	[SW Version] V15.502.6 + patch0319 [Observed Symptom] VOD playback fails, if retrying VOD playback just after exit. The symptom keeps until retrying playback several times. [Expected Result] There should not be any problem to play VOD. [Reproducibility] over 50% --------------------------------------------------------- [SW 버전] V15.502.6 + patch0319 [발생 현상] VOD 재생 중 RCU "나가기" 버튼 누른 후 바로 다시 재생 시도 시, "일시적인 장애로 서비스가 원활하지 않습니다. 잠시 후 다시 이용해 주세요." 메시지 표시되면 VOD 재생 안됨 문제 증상 지속 유지되며, 여러 번 재시도 할 경우 정상 재생되는 경우 있음 [정상 동작] VOD 재생에 문제가 없어야 함 [발생 빈도] 50% 이상
Steps To Reproduce	1) Play a paid VOD content 2) Press "Exit(나가기)" key on RCU 3) Try to play the same content just after step 2) --------------------------------------------------------- 1) 구매한 VOD 컨텐츠 재생 2) RCU의 "나가기" 버튼으로 재생 종료 3) 재생 종료 직 후, 동일 컨텐츠 재생 시도
Tags	No tags attached.
Attach Tags	(Separate by ",")

User List	There are no users monitoring this issue. Username

(ALTech) Jewoo Lee 2019-03-25 16:56 developer	log_VOD.play.failure.txt (9,987,598 bytes) VID_20190325_173430.mp4 (40,340,426 bytes) VID_20190325_173430.mp4 (40,340,426 bytes)

(Amlogic) Jingyu Xu 2019-03-27 17:00 developer ~0001747	I tried 20 times on 3/27 version, and did not reproduce the issue. "일시적인 장애로 서비스가 원활하지 않습니다. 잠시 후 다시 이용해 주세요." this message could be caused by network environment.

(SW) Jacky Chiang 2019-04-30 13:43 manager ~0002317 Last edited: 2019-04-30 14:06	Hi Mr. Lee, Could you kindly use SW v15.502.15 to verify it again ? Our engineer, Jim will cowork with you for verification. I think AML already improved and fine tune IPTV player over one month and the latest SW version should fix this issue.

(SW) Jim Chen 2019-05-01 18:56 developer ~0002347	Reproduced on r15a with the Dynasore VOD (about 1/10 rate), but not reproduced with "THE UNIVERSE" VOD. The error message is shown when error code "Error (10000,201)" happen. I found the value in Onnuri-SKB_RTSP_SDK_API_Manual-v2.0.docx and it's defined to RET_INCORRECT_MSG Not sure if this is the right place but we can ask TVS first.

(SW) Jim Chen 2019-05-02 19:35 developer ~0002359	mostly the error code is 105, and the player will retry, but if the error code is 201, then it will "show error and exit" (check attached). After discuss with AML and TVS, they think we should dispatch this issue to zooinnet on Jira.

(SW) Jim Chen 2019-05-02 19:35 developer	105_201.png (181,585 bytes) 105_201.png (181,585 bytes)

(SW) Jim Chen 2019-05-02 19:37 developer ~0002360	Sorry attach again 105_201-2.png (239,653 bytes) 105_201-2.png (239,653 bytes)

(SW) Jim Chen 2019-05-03 14:58 developer ~0002369	Hi Jewoo, Attached full log of the capture image. vod_reproduced.logcat (203,124 bytes) 05-01 18:32:06.820 E/HalPlayer(19500): setAudioOutputType:261 return false <- !mHal 05-01 18:32:07.840 E/HalPlayer(19500): setAudioOutputType:261 return false <- !mHal 05-01 18:32:08.865 E/HalPlayer(19500): setAudioOutputType:261 return false <- !mHal 05-01 18:32:08.934 D/audio-subMixingFactory( 2998): out_standby_subMixingPCM: out_stream(0xa8b04000) usecase: STREAM_PCM_NORMAL 05-01 18:32:08.934 I/audio-subMixingFactory( 2998): ++usecase_change_validate_l_sm(), dev usecase masks = 0x1, is_standby = 1, out usecase STREAM_PCM_NORMAL 05-01 18:32:08.934 I/audio-subMixingFactory( 2998): --usecase_change_validate_l_sm(), dev usecase masks = 0, is_standby = 1, out usecase STREAM_PCM_NORMAL 05-01 18:32:08.934 I/amlaudioMixer( 2998): ++delete_mixer_input_port port ID: 0 05-01 18:32:08.937 E/amlaudioMixer( 2998): mixer_do_mixing_16bit(), sys or direct pcm must exist!!! 05-01 18:32:09.556 D/OkHttp ( 5687): --> PUT https://stg-api.sktnugu.com/v1/setting/devices/ALDFBK5D4KG062945D2D/status http/1.1 05-01 18:32:09.556 D/OkHttp ( 5687): Content-Type: application/json; charset=UTF-8 05-01 18:32:09.556 D/OkHttp ( 5687): Content-Length: 149 05-01 18:32:09.561 D/OkHttp ( 5687): Auth-Token: 5F0AAF7446AA41FC9BD32367B520CD64 05-01 18:32:09.562 D/OkHttp ( 5687): User-Id: ALDFBK5D4KBH6293772D 05-01 18:32:09.562 D/OkHttp ( 5687): Device-Id: ALDFBK5D4KG062945D2D 05-01 18:32:09.562 D/OkHttp ( 5687): Device-Type-Code: DVC_ATV_FOXCONN_AND 05-01 18:32:09.562 D/OkHttp ( 5687): Os-Type-Code: MBL_AND 05-01 18:32:09.562 D/OkHttp ( 5687): Os-Version: 9 05-01 18:32:09.562 D/OkHttp ( 5687): App-Version: 2.3.7_test4_stg 05-01 18:32:09.562 D/OkHttp ( 5687): Sdk-Version: 4.2.0 05-01 18:32:09.562 D/OkHttp ( 5687): Firmware-Version: 15.502.15 05-01 18:32:09.562 D/OkHttp ( 5687): {"device":{"batteryAmount":100,"bluetoothOnOff":"on","maxVolumeLevel":32,"micOnOff":"on","minVolumeLevel":0,"moodLightOnOff":"off","vol umeLevel":16}} 05-01 18:32:09.562 D/OkHttp ( 5687): --> END PUT (149-byte body) 05-01 18:32:09.656 D/OkHttp ( 5687): <-- 200 https://stg-api.sktnugu.com/v1/setting/devices/ALDFBK5D4KG062945D2D/status (93ms) 05-01 18:32:09.656 D/OkHttp ( 5687): Server: nginx 05-01 18:32:09.656 D/OkHttp ( 5687): Date: Wed, 01 May 2019 09:32:10 GMT 05-01 18:32:09.656 D/OkHttp ( 5687): Content-Type: application/json;charset=UTF-8 05-01 18:32:09.656 D/OkHttp ( 5687): Transfer-Encoding: chunked 05-01 18:32:09.656 D/OkHttp ( 5687): Connection: keep-alive 05-01 18:32:09.656 D/OkHttp ( 5687): X-Application-Context: api-gateway:stg:8080 05-01 18:32:09.656 D/OkHttp ( 5687): Set-Cookie: SCOUTER=z5ic12755vihm; Max-Age=2147483647; Expires=Mon, 19-May-2087 12:46:17 GMT 05-01 18:32:09.661 D/OkHttp ( 5687): {} 05-01 18:32:09.661 D/OkHttp ( 5687): <-- END HTTP (2-byte body) 05-01 18:32:09.890 E/HalPlayer(19500): setAudioOutputType:261 return false <- !mHal 05-01 18:32:10.056 E/ZooinNet(19500): [2019-05-01 18:32:10.056][tid:2760898928] [vTV-BOX-F_0.0][ERR] [RTSP_DESCRIBE:1249] <110.12.45.204-41958> SDP control not found 05-01 18:32:10.056 I/ZooinNet(19500): [2019-05-01 18:32:10.056][tid:2760898928] [vTV-BOX-F_0.0][NOT] [RTSP_DESCRIBE:1251] <110.12.45.204-41958> _pSession->OnPlayReturn call the message of RET_INCORRECT_MSG 05-01 18:32:10.056 D/ZooVodReceiver(19500): onPlayReturn : begin 05-01 18:32:10.056 D/ZooVodReceiver(19500): onPlayReturn : found ZooVodReceiver instance by pSession(0xa58835b0)->fIdentifier(5) 05-01 18:32:10.056 D/ZooVodReceiver(19500): onPlayReturn : [fIdentifier(5)] eRetCode = [RET_INCORRECT_MSG](201) 05-01 18:32:10.056 E/ZooVodReceiver(19500): onPlayReturn : eRetCode(201) is ERR so RecvDeny flag SET!!! 05-01 18:32:10.056 D/ZooVodReceiver(19500): notifyError : begin 05-01 18:32:10.056 I/TVSMMFEvent(19500): event: source (7), type(ERROR), what(10000), extra(201) 05-01 18:32:10.057 D/ZooVodReceiver(19500): notifyError : end 05-01 18:32:10.057 D/TVSMediaServerImpl(19500): onNotifyMediaServerEvent() >> begin 05-01 18:32:10.057 D/ZooVodReceiver(19500): onPlayReturn : end 05-01 18:32:10.057 D/ZooVodReceiver(19500): setup : VOD server - 1.255.94.11(1ff5e0b):554 05-01 18:32:10.057 D/ZooVodReceiver(19500): notifyInfo : begin 05-01 18:32:10.057 D/TVSMMFEvent(19500): type=2, what=100003, extra=33512971 05-01 18:32:10.057 I/TVSMMFEvent(19500): event: source (7), type(INFO), what(100003), extra(33512971) 05-01 18:32:10.057 D/TVSMediaServerImpl(19500): onNotifyMediaServerEvent() << end 0 ms 05-01 18:32:10.057 D/ZooVodReceiver(19500): notifyInfo : end 05-01 18:32:10.057 D/TVSMediaServerImpl(19500): onNotifyMediaServerEvent() >> begin 05-01 18:32:10.057 D/ZooVodReceiver(19500): notifyInfo : begin 05-01 18:32:10.057 I/TVSMMFClient( 4081): ===== tvs_mediaserver event callback: 0xad40a52c 05-01 18:32:10.057 D/TVSMMFEvent(19500): type=2, what=100004, extra=554 05-01 18:32:10.057 I/TVSMMFClient( 4020): ===== tvs_mediaserver event callback: 0xa35b6e3c 05-01 18:32:10.057 I/TVSMMFEvent(19500): event: source (7), type(INFO), what(100004), extra(554) 05-01 18:32:10.057 I/JNI_Settings( 4020): tvsMediaCallback(10000) 05-01 18:32:10.057 D/TVSMediaServerImpl(19500): onNotifyMediaServerEvent() << end 0 ms 05-01 18:32:10.057 I/JNI_Settings( 4020): what(10000), extra(201), data size(184) 05-01 18:32:10.057 D/ZooVodReceiver(19500): notifyInfo : end 05-01 18:32:10.057 D/TVSMediaServerImpl(19500): onNotifyMediaServerEvent() >> begin 05-01 18:32:10.057 D/ChannelMediaEventManager( 4081): Event : 10000, Source : 7, URI : skbvod://cdn2.hanafostv.com:554/VOD/51/CS11010351/CE1000163529_0_TVHD_201903120315.ts. pac?ci={AEC9362D-3C61-4F98-95B4-1C08FC43BF21}&oi=747aCE1000163529_0_TVHD_2019&op=4a0d1999030&rp=51 05-01 18:32:10.057 E/ZooVodReceiver(19500): setup : Setup() call failed. return value = -1 05-01 18:32:10.057 E/ZooVodReceiver(19500): Setup returns 300, m_session.ip=1.255.94.11, m_session.port=554 05-01 18:32:10.057 D/DatacastingManager( 4081): [371] Event Type : 10000, Source : 7, Event Data(URI) : skbvod://cdn2.hanafostv.com:554/VOD/51/CS11010351/CE1000163529_0_TVHD _201903120315.ts.pac?ci={AEC9362D-3C61-4F98-95B4-1C08FC43BF21}&oi=747aCE1000163529_0_TVHD_2019&op=4a0d1999030&rp=51 05-01 18:32:10.057 D/ZooVodReceiver(19500): notifyError : begin 05-01 18:32:10.057 D/TVSMediaServerImpl(19500): onNotifyMediaServerEvent() << end 0 ms 05-01 18:32:10.057 I/TVSMMFEvent(19500): event: source (7), type(ERROR), what(10000), extra(300) 05-01 18:32:10.057 D/ZooVodReceiver(19500): notifyError : end 05-01 18:32:10.057 D/TVSMediaServerImpl(19500): onNotifyMediaServerEvent() >> begin 05-01 18:32:10.057 E/SKBVodSource(19500): ZooVodReceiver::setup() is failure 05-01 18:32:10.057 D/MediaEventHandler( 4081): [114] Event Type : 10000, Source : 7, Event Data(URI) : skbvod://cdn2.hanafostv.com:554/VOD/51/CS11010351/CE1000163529_0_TVHD_ 201903120315.ts.pac?ci={AEC9362D-3C61-4F98-95B4-1C08FC43BF21}&oi=747aCE1000163529_0_TVHD_2019&op=4a0d1999030&rp=51 05-01 18:32:10.057 I/TVSMMFClient( 4020): ===== tvs_mediaserver event callback: 0xa35b6e3c 05-01 18:32:10.057 D/SKBVodSource(19500): _onOpen() << end 4043 ms 05-01 18:32:10.057 D/MediaEventHandler( 4081): [114] Ignore Event Type : 10000 05-01 18:32:10.057 E/SKBVodSource(19500): _onOpen() takes 4043 ms, Seems slow, check! 05-01 18:32:10.057 I/JNI_Settings( 4020): tvsMediaCallback(100003) 05-01 18:32:10.057 D/TVSMediaServerImpl(19500): onNotifyMediaServerEvent() << end 0 ms 05-01 18:32:10.057 I/JNI_Settings( 4020): what(100003), extra(33512971), data size(184) 05-01 18:32:10.057 I/TVSMMFClient( 4081): ===== tvs_mediaserver event callback: 0xad40a52c 05-01 18:32:10.058 D/TVSGtvPlayer( 3097): onPlayerEvent() >> begin 05-01 18:32:10.058 D/TVSGtvPlayer( 3097): event : type(1), what(10000), extra(201) 05-01 18:32:10.058 I/TVSMMFClient( 4081): ===== tvs_mediaserver event callback: 0xad40a52c 05-01 18:32:10.058 D/TVSGtvPlayer( 3097): onErrorEvent() >> begin 05-01 18:32:10.058 I/TVSMMFClient( 4020): ===== tvs_mediaserver event callback: 0xa35b6e3c 05-01 18:32:10.058 I/TVSMMFClient( 4081): ===== tvs_mediaserver event callback: 0xad40a52c 05-01 18:32:10.058 E/TVSGtvPlayer( 3097): notify ERROR : 10000, 201 05-01 18:32:10.058 I/JNI_Settings( 4020): tvsMediaCallback(100004) 05-01 18:32:10.058 D/TVSGtvPlayer( 3097): sendEvent() >> begin 05-01 18:32:10.058 I/JNI_Settings( 4020): what(100004), extra(554), data size(184) 05-01 18:32:10.058 E/TVSGtvPlayer( 3097): MEDIA_ERROR 05-01 18:32:10.058 D/ChannelMediaEventManager( 4081): Event : 10000, Source : 7, URI : skbvod://cdn2.hanafostv.com:554/VOD/51/CS11010351/CE1000163529_0_TVHD_201903120315.ts. pac?ci={AEC9362D-3C61-4F98-95B4-1C08FC43BF21}&oi=747aCE1000163529_0_TVHD_2019&op=4a0d1999030&rp=51 05-01 18:32:10.058 D/TVSGtvPlayer( 3097): onSendEvent() >> begin 05-01 18:32:10.058 I/TVSMMFClient( 4020): ===== tvs_mediaserver event callback: 0xa35b6e3c 05-01 18:32:10.058 D/DatacastingManager( 4081): [371] Event Type : 10000, Source : 7, Event Data(URI) : skbvod://cdn2.hanafostv.com:554/VOD/51/CS11010351/CE1000163529_0_TVHD _201903120315.ts.pac?ci={AEC9362D-3C61-4F98-95B4-1C08FC43BF21}&oi=747aCE1000163529_0_TVHD_2019&op=4a0d1999030&rp=51 05-01 18:32:10.058 I/JNI_Settings( 4020): tvsMediaCallback(10000) 05-01 18:32:10.058 I/JNI_Settings( 4020): what(10000), extra(300), data size(184) 05-01 18:32:10.058 D/TVSGtvPlayer( 3097): onSendEvent() << end 0 ms 05-01 18:32:10.058 D/TVSGtvPlayer( 3097): sendEvent() << end 0 ms 05-01 18:32:10.058 D/TVSGtvPlayer( 3097): onErrorEvent() << end 0 ms 05-01 18:32:10.058 D/MediaEventHandler( 4081): [114] Event Type : 10000, Source : 7, Event Data(URI) : skbvod://cdn2.hanafostv.com:554/VOD/51/CS11010351/CE1000163529_0_TVHD_ 201903120315.ts.pac?ci={AEC9362D-3C61-4F98-95B4-1C08FC43BF21}&oi=747aCE1000163529_0_TVHD_2019&op=4a0d1999030&rp=51 05-01 18:32:10.058 D/TVSGtvPlayer( 3097): onPlayerEvent() << end 0 ms 05-01 18:32:10.058 D/MediaEventHandler( 4081): [114] Ignore Event Type : 10000 05-01 18:32:10.058 D/TVSGtvPlayer( 3097): onPlayerEvent() >> begin 05-01 18:32:10.058 D/TVSGtvPlayer( 3097): event : type(2), what(100003), extra(33512971) 05-01 18:32:10.058 D/TVSGtvPlayer( 3097): notify INFO : 100003, 33512971 05-01 18:32:10.058 D/TVSGtvPlayer( 3097): sendEvent() >> begin 05-01 18:32:10.058 D/TVSGtvPlayer( 3097): onSendEvent() >> begin 05-01 18:32:10.058 D/TVSGtvPlayer( 3097): onSendEvent() << end 0 ms 05-01 18:32:10.058 D/TVSGtvPlayer( 3097): sendEvent() << end 0 ms 05-01 18:32:10.058 D/TVSGtvPlayer( 3097): onPlayerEvent() << end 0 ms 05-01 18:32:10.058 D/TVSGtvPlayer( 3097): onPlayerEvent() >> begin 05-01 18:32:10.058 D/TVSGtvPlayer( 3097): event : type(2), what(100004), extra(554) 05-01 18:32:10.058 D/TVSGtvPlayer( 3097): notify INFO : 100004, 554 05-01 18:32:10.058 D/TVSGtvPlayer( 3097): sendEvent() >> begin 05-01 18:32:10.058 D/TVSGtvPlayer( 3097): onSendEvent() >> begin 05-01 18:32:10.059 D/TVSGtvPlayer( 3097): onSendEvent() << end 0 ms 05-01 18:32:10.059 D/TVSGtvPlayer( 3097): sendEvent() << end 0 ms 05-01 18:32:10.059 D/TVSGtvPlayer( 3097): onPlayerEvent() << end 0 ms 05-01 18:32:10.059 D/TVSGtvPlayer( 3097): onPlayerEvent() >> begin 05-01 18:32:10.059 D/TVSGtvPlayer( 3097): event : type(1), what(10000), extra(300) 05-01 18:32:10.059 D/TVSGtvPlayer( 3097): onErrorEvent() >> begin 05-01 18:32:10.059 E/TVSGtvPlayer( 3097): notify ERROR : 10000, 300 05-01 18:32:10.059 D/TVSGtvPlayer( 3097): sendEvent() >> begin 05-01 18:32:10.059 E/TVSGtvPlayer( 3097): MEDIA_ERROR 05-01 18:32:10.059 D/TVSGtvPlayer( 3097): onSendEvent() >> begin 05-01 18:32:10.059 D/TVSGtvPlayer( 3097): onSendEvent() << end 0 ms 05-01 18:32:10.059 D/TVSGtvPlayer( 3097): sendEvent() << end 0 ms 05-01 18:32:10.059 D/TVSGtvPlayer( 3097): onErrorEvent() << end 0 ms 05-01 18:32:10.059 D/TVSGtvPlayer( 3097): onPlayerEvent() << end 1 ms 05-01 18:32:10.073 D/SKBVodSource(19500): _onStart() >> begin 05-01 18:32:10.073 D/ZooVodReceiver(19500): play : begin 05-01 18:32:10.073 E/ZooinNet(19500): [2019-05-01 18:32:10.073][tid:2760898928] [vTV-BOX-F_0.0][ERR] [Seek:3993] <-0> calling IN 05-01 18:32:10.073 I/ZooinNet(19500): [2019-05-01 18:32:10.073][tid:2760898928] [vTV-BOX-F_0.0][NOT] [Seek:3997] <-0> _pSession->OnPlayReturn call the message of RET_INCORRE CT_SCALE 05-01 18:32:10.073 D/ZooVodReceiver(19500): onPlayReturn : begin 05-01 18:32:10.073 D/ZooVodReceiver(19500): onPlayReturn : found ZooVodReceiver instance by pSession(0xa58835b0)->fIdentifier(5) 05-01 18:32:10.073 D/ZooVodReceiver(19500): onPlayReturn : [fIdentifier(5)] eRetCode = [RET_INCORRECT_SCALE](205) 05-01 18:32:10.073 D/ZooVodReceiver(19500): onPlayReturn : end 05-01 18:32:10.073 D/ZooVodReceiver(19500): Seek elapsedTime = 0msec 05-01 18:32:10.073 E/ZooVodReceiver(19500): [fIdentifier:5] Seek() is failure. returns -1. pos(51.000000) 05-01 18:32:10.074 D/SKBVodSource(19500): _onStart() << end 0 ms 05-01 18:32:10.074 D/PMTDetector(19500): detect() >> begin 05-01 18:32:10.074 I/TSFeeder(19500): needFeeder = 1, src=0xa8cee310, dst=0xa8cee010 05-01 18:32:10.074 I/TSFeeder(19500): Feeder start! 05-01 18:32:10.075 I/PMTDetector(19500): Waiting max 2 seconds to detect PMT 05-01 18:32:10.923 E/HalPlayer(19500): setAudioOutputType:261 return false <- !mHal 05-01 18:32:11.943 E/HalPlayer(19500): setAudioOutputType:261 return false <- !mHal 05-01 18:32:12.075 I/PMTDetector(19500): Waiting max 2 seconds to detect PMT.. done 05-01 18:32:12.075 I/PMTDetector(19500): ==================================================================== 05-01 18:32:12.075 I/PMTDetector(19500): PMT detect result 05-01 18:32:12.075 I/PMTDetector(19500): -------------------------------------------------------------------- 05-01 18:32:12.076 I/PMTDetector(19500): Detected : false 05-01 18:32:12.076 I/PMTDetector(19500): Program number : 0 05-01 18:32:12.076 I/PMTDetector(19500): PMT pid : 0x1fff 05-01 18:32:12.076 I/PMTDetector(19500): sections : 0x0 05-01 18:32:12.076 I/PMTDetector(19500): ==================================================================== 05-01 18:32:12.076 D/PMTDetector(19500): detect() << end 2003 ms 05-01 18:32:12.076 E/PMTDetector(19500): detect() takes 2003 ms, Seems slow, check! 05-01 18:32:12.079 D/HalPlayer(19500): open() >> begin 05-01 18:32:12.079 D/AmlHalFactory(19500): createPlayer() >> begin 05-01 18:32:12.079 D/AmlHalPlayer(19500): AmlHalPlayer() >> begin 05-01 18:32:12.079 D/AmlHalPlayer(19500): AmlHalPlayer() << end 0 ms 05-01 18:32:12.079 D/AmlHalFactory(19500): createPlayer() << end 0 ms 05-01 18:32:12.079 D/AmlHalPlayer(19500): open() >> begin 05-01 18:32:12.079 I/AmlHalPlayer(19500): open, 0xa8ca7330, zorder=-1, pip=0, background=0--- 05-01 18:32:12.079 I/AmlHalPlayer(19500): open, video.isValid()=0, trackCount=0, audio.tracks[0].isValid()=0, isAudioOnlyChannel=0 05-01 18:32:12.079 D/AmlDemux(19500): AmlDemux() >> begin 05-01 18:32:12.079 I/AmlDemux(19500): AmlDemux ------ zorder:-1 05-01 18:32:12.079 D/AmlDemux(19500): AmlDemux() << end 0 ms 05-01 18:32:12.079 D/AmlDemux(19500): open() >> begin 05-01 18:32:12.080 D/AmlDemux(19500): Handle: 1 05-01 18:32:12.080 I/MiniTSDemux(19500): [MiniTSDemux:208] 05-01 18:32:12.080 W/MiniTSDemux(19500): zorder = -1 05-01 18:32:12.080 D/AmlDemux(19500): Demux handler:0xa8ca7500 05-01 18:32:12.080 D/AmlDemux(19500): open() << end 0 ms 05-01 18:32:12.080 D/AmlHalPlayer(19500): Audio buf size: 32768 05-01 18:32:12.080 D/AmlHalPlayer(19500): Video buf size: 4194304 05-01 18:32:12.080 I/PlayerRoster(19500): register idHint:0 05-01 18:32:12.080 D/Chronicle_0(19500): chronicle: birth at: 3671419385 05-01 18:32:12.081 D/AmlHalPlayer(19500): changeStc() >> begin 05-01 18:32:12.081 I/AmlHalPlayer(19500): [changeStc,205], stc.policy=1 05-01 18:32:12.081 D/AmlHalPlayer(19500): FIRST PTS MODE! - but HiSilicon doesnot have this mode. use audio mode instead. 05-01 18:32:12.090 D/AmlHalPlayer(19500): changeStc() << end 10 ms 05-01 18:32:12.090 D/AmlAudio(19500): AmlAudio() >> begin 05-01 18:32:12.090 D/AmlAudio(19500): AmlAudio() << end 0 ms 05-01 18:32:12.090 I/AmlHalPlayer(19500): [open,136], #### zorder=0,audio:0xa8ca75ac 05-01 18:32:12.090 D/AmlAudio(19500): open() >> begin 05-01 18:32:12.090 I/AmlAudio(19500): open--0xa8ca75ac 05-01 18:32:12.091 I/AmlAudio(19500): ======================================================================================== 05-01 18:32:12.091 I/AmlAudio(19500): 05-01 18:32:12.091 I/AmlAudio(19500): dumpAudioInfo: count:0, mute:0, selectedTrackIndex=-1 05-01 18:32:12.091 I/AmlAudio(19500): 05-01 18:32:12.091 I/AmlAudio(19500): ======================================================================================== 05-01 18:32:12.091 D/AmlAudio(19500): change() >> begin 05-01 18:32:12.091 I/AmlAudio(19500): ======================================================================================== 05-01 18:32:12.091 I/AmlAudio(19500): 05-01 18:32:12.091 I/AmlAudio(19500): dumpAudioInfo: count:0, mute:0, selectedTrackIndex=-1 05-01 18:32:12.091 I/AmlAudio(19500): 05-01 18:32:12.091 I/AmlAudio(19500): ======================================================================================== 05-01 18:32:12.091 D/AmlAudio(19500): mConfig.trackCount:0, mConfig.selectedTrackIndex:-1 05-01 18:32:12.091 D/AmlAudio(19500): No audio info : stop 05-01 18:32:12.091 D/AmlAudio(19500): doStop() >> begin 05-01 18:32:12.091 D/AmlAudio(19500): doStop() << end 0 ms 05-01 18:32:12.091 D/AmlAudio(19500): change() << end 0 ms 05-01 18:32:12.091 D/AmlAudio(19500): open() << end 0 ms 05-01 18:32:12.091 D/AmlVideo(19500): AmlVideo() >> begin 05-01 18:32:12.091 D/AmlVideo(19500): AmlVideo() << end 0 ms 05-01 18:32:12.091 D/AmlVideo(19500): open() >> begin 05-01 18:32:12.091 I/AmlVideo(19500): [open,62], isValid=0, codec=0x0, deocde=1, zorder=0, keepLastFrame=1, isStretch=0 05-01 18:32:12.091 D/AmlVideo(19500): change() >> begin 05-01 18:32:12.091 D/AmlVideo(19500): Video config is invalid : remove video 05-01 18:32:12.091 D/AmlVideo(19500): doStop() >> begin 05-01 18:32:12.091 D/AmlVideo(19500): doStop() << end 0 ms 05-01 18:32:12.091 D/AmlVideo(19500): start() >> begin 05-01 18:32:12.091 D/AmlVideo(19500): start() << end 0 ms 05-01 18:32:12.091 D/AmlVideo(19500): change() << end 0 ms 05-01 18:32:12.091 D/AmlVideo(19500): open() << end 1 ms 05-01 18:32:12.091 I/AmlHalPlayer(19500): set audio output type: 0 05-01 18:32:12.092 I/AmlHalPlayer(19500): set network jitter:300 ms 05-01 18:32:12.092 I/MiniTSDemux(19500): 673 setNetworkJitter:300 ms 05-01 18:32:12.092 I/AmlVideo(19500): arg delayMS = -1 05-01 18:32:12.092 I/AmlAudio(19500): arg delayMS = -1 05-01 18:32:12.092 I/AmlHalPlayer(19500): [open,155], zorder=0, 05-01 18:32:12.092 D/AmlHalPlayer(19500): open() << end 12 ms 05-01 18:32:12.093 I/MiniTSDemux(19500): [setPCRListener:667] pcr listener:0xa8c8a4d8 05-01 18:32:12.093 D/HalPlayer(19500): open() << end 15 ms 05-01 18:32:12.094 D/HalPlayer(19500): update() >> begin 05-01 18:32:12.094 D/HalPlayer(19500): No need to update stc 05-01 18:32:12.094 D/HalPlayer(19500): audio need to change: track count, but set again because of multi audio decoder in SKB 05-01 18:32:12.094 I/HalPlayer(19500): Need to change AUDIO 05-01 18:32:12.094 D/AmlAudio(19500): change() >> begin 05-01 18:32:12.094 I/AmlAudio(19500): ======================================================================================== 05-01 18:32:12.094 I/AmlAudio(19500): 05-01 18:32:12.094 I/AmlAudio(19500): dumpAudioInfo: count:0, mute:0, selectedTrackIndex=-1 05-01 18:32:12.094 I/AmlAudio(19500): 05-01 18:32:12.094 I/AmlAudio(19500): ======================================================================================== 05-01 18:32:12.094 D/AmlAudio(19500): mConfig.trackCount:0, mConfig.selectedTrackIndex:-1 05-01 18:32:12.094 D/AmlAudio(19500): No audio info : stop 05-01 18:32:12.094 D/AmlAudio(19500): doStop() >> begin 05-01 18:32:12.094 D/AmlAudio(19500): doStop() << end 0 ms 05-01 18:32:12.094 D/AmlAudio(19500): change() << end 0 ms 05-01 18:32:12.095 D/HalPlayer(19500): No need to update video 05-01 18:32:12.095 D/HalPlayer(19500): update() << end 0 ms 05-01 18:32:12.095 D/TVSGtvPlayer( 3097): onOptionChanged() >> begin 05-01 18:32:12.095 D/TVSGtvPlayer( 3097): onOptionChanged: type=1, value=true 05-01 18:32:12.095 I/SubtitleCCRenderer( 3097): onOptionChanged. key(1) value(true) 05-01 18:32:12.095 D/TVSGtvPlayer( 3097): onOptionChanged() << end 0 ms 05-01 18:32:12.096 D/HalPlayer(19500): getMute() >> begin 05-01 18:32:12.096 D/AmlVideo(19500): getMute() >> begin 05-01 18:32:12.096 I/AmlVideo(19500): getMute():228 NOT IMPLEMENTED 05-01 18:32:12.096 D/AmlVideo(19500): getMute() << end 0 ms 05-01 18:32:12.096 D/AmlAudio(19500): getMute() >> begin 05-01 18:32:12.096 I/AmlAudio(19500): #### 0xa8ca75ac getMute: mute=1 05-01 18:32:12.096 D/AmlAudio(19500): getMute() << end 1 ms 05-01 18:32:12.096 D/HalPlayer(19500): getMute() << end 1 ms 05-01 18:32:12.096 D/HalPlayer(19500): setMute() >> begin 05-01 18:32:12.096 D/AmlVideo(19500): setMute() >> begin 05-01 18:32:12.097 I/AmlVideo(19500): setMute():235 NOT IMPLEMENTED 05-01 18:32:12.097 D/AmlVideo(19500): setMute() << end 0 ms 05-01 18:32:12.097 D/AmlAudio(19500): setMute() >> begin 05-01 18:32:12.097 D/AmlAudio(19500): setMute() << end 0 ms 05-01 18:32:12.097 D/HalPlayer(19500): setMute() << end 0 ms 05-01 18:32:12.098 D/TVSMMFEvent(19500): type=2, what=10000, extra=1000 05-01 18:32:12.098 I/TVSMMFEvent(19500): event: source (7), type(INFO), what(10000), extra(1000) 05-01 18:32:12.098 D/TVSGtvPlayer( 3097): sendEvent() >> begin 05-01 18:32:12.098 D/TVSGtvPlayer( 3097): onPlayerEvent() >> begin 05-01 18:32:12.098 D/TVSGtvPlayer( 3097): onSendEvent() >> begin 05-01 18:32:12.098 D/TVSGtvPlayer( 3097): event : type(2), what(10000), extra(1000) 05-01 18:32:12.098 D/TVSGtvPlayer( 3097): notify INFO : 10000, 1000 05-01 18:32:12.098 D/TVSGtvPlayer( 3097): sendEvent() >> begin 05-01 18:32:12.098 D/TVSGtvPlayer( 3097): onSendEvent() >> begin 05-01 18:32:12.098 D/TVSGtvPlayer( 3097): onSendEvent() << end 0 ms 05-01 18:32:12.098 D/TVSGtvPlayer( 3097): onSendEvent() << end 0 ms 05-01 18:32:12.098 D/TVSGtvPlayer( 3097): sendEvent() << end 0 ms 05-01 18:32:12.098 D/TVSGtvPlayer( 3097): sendEvent() << end 0 ms 05-01 18:32:12.098 D/TVSGtvPlayer( 3097): updateVideoSurface() >> begin 05-01 18:32:12.098 D/TVSGtvPlayer( 3097): invokeSkb() << end 6079 ms 05-01 18:32:12.098 D/TVSGtvPlayer( 3097): onPlayerEvent() << end 1 ms 05-01 18:32:12.098 D/TVSGtvPlayer( 3097): VideoSurface(0x0), NativeHandle(0x0) 05-01 18:32:12.098 E/TVSGtvPlayer( 3097): invokeSkb() takes 6079 ms, Seems slow, check! 05-01 18:32:12.098 D/TVSGtvPlayer( 3097): updateVideoSurface() << end 1 ms 05-01 18:32:12.098 D/TVSGtvPlayer( 3097): onPrepare() << end 6087 ms 05-01 18:32:12.098 E/TVSGtvPlayer( 3097): onPrepare() takes 6087 ms, Seems slow, check! 05-01 18:32:12.098 E/MediaPlayerNative( 8541): error (10000, 201) 05-01 18:32:12.098 W/MediaPlayerNative( 8541): info/warning (100003, 33512971) 05-01 18:32:12.098 W/MediaPlayerNative( 8541): info/warning (100004, 554) 05-01 18:32:12.099 E/MediaPlayerNative( 8541): error (10000, 300) 05-01 18:32:12.099 W/MediaPlayerNative( 8541): info/warning (10000, 1000) 05-01 18:32:12.099 I/Choreographer( 8541): Skipped 364 frames! The application may be doing too much work on its main thread. 05-01 18:32:12.100 D/VODPlayerUIControl( 8541): SSMIISManager\|Handler 32201 05-01 18:32:12.100 D/VODPlayerUIControl( 8541): isUsableScreenSearch\|mediaType: VOD, vod type: MOVIE true\| 05-01 18:32:12.100 D/VODPlayerUIControl( 8541): SSMIISManager\| isUsableScreenSearch: true 05-01 18:32:12.100 D/VODPlayerUIControl( 8541): isUsableScreenSearch\|mediaType: VOD, vod type: MOVIE true\| 05-01 18:32:12.100 D/VODPlayerUIControl( 8541): isUsableScreenSearch\|mediaType: VOD, vod type: MOVIE true\| 05-01 18:32:12.101 D/VODPlayerUIControl( 8541): isEnableScreenSearch\|MOVIE, size: 10, true\| 05-01 18:32:12.101 D/VODPlayerUIControl( 8541): SSMIISManager\| isEnableScreenSearch: true, size: 10 05-01 18:32:12.101 D/VODPlayerUIControl( 8541): isUsableScreenSearch\|mediaType: VOD, vod type: MOVIE true\| 05-01 18:32:12.101 D/VODPlayerUIControl( 8541): isEnableScreenSearch\|MOVIE, size: 10, true\| 05-01 18:32:12.101 D/VODPlayerUIControl( 8541): setScreenSearch\|count: 10\| 05-01 18:32:12.101 I/ISQMSManager( 8541): mAgentSendHandler.handleMessage() called. MESSAGE_RESPONSE_AGENT_OK 05-01 18:32:12.105 E/MediaPlayer( 8541): Error (10000,201) 05-01 18:32:12.105 I/BtvMediaPlayer( 8541): onError what: 10000, extra: 201 05-01 18:32:12.105 I/BtvMediaPlayer( 8541): setState PlaybackState: Error 05-01 18:32:12.106 I/BtvMediaPlayer( 8541): resetMediaPlayer 05-01 18:32:12.106 E/VODPlayer( 8541): VOD Play OnError [10000][201] 05-01 18:32:12.106 I/BtvMediaPlayer( 8541): [resetMediaPlayer] before MediaPlayer.setOnPreparedListener() 05-01 18:32:12.106 I/BtvMediaPlayer( 8541): [resetMediaPlayer] after MediaPlayer.setOnPreparedListener() 05-01 18:32:12.106 I/BtvMediaPlayer( 8541): [resetMediaPlayer] before MediaPlayer.setOnInfoListener() 05-01 18:32:12.106 I/BtvMediaPlayer( 8541): [resetMediaPlayer] after MediaPlayer.setOnInfoListener() 05-01 18:32:12.106 I/BtvMediaPlayer( 8541): [resetMediaPlayer] before MediaPlayer.setOnErrorListener() 05-01 18:32:12.106 I/BtvMediaPlayer( 8541): [resetMediaPlayer] after MediaPlayer.setOnErrorListener() 05-01 18:32:12.106 I/server-module( 8541): CheckNetwork boolean is true 05-01 18:32:12.106 I/BtvMediaPlayer( 8541): [resetMediaPlayer] before MediaPlayer.setOnCompletionListener() 05-01 18:32:12.106 I/BtvMediaPlayer( 8541): setPlayerCallback 05-01 18:32:12.106 I/BtvMediaPlayer( 8541): [resetMediaPlayer] after MediaPlayer.setOnCompletionListener() 05-01 18:32:12.106 I/BtvMediaPlayer( 8541): [resetMediaPlayer] before MediaPlayer.setDisplay() 05-01 18:32:12.106 I/CDR ( 8541): Send VOD Log [EVENT_TYPE_PLAY_STOP] 05-01 18:32:12.106 I/CDR ( 8541): Send VOD Log {AEC9362D-3C61-4F98-95B4-1C08FC43BF21} 0/0 53 : seeing path = ETC(99) , code = 004 05-01 18:32:12.106 I/BtvMediaPlayer( 8541): [resetMediaPlayer] after MediaPlayer.setDisplay() 05-01 18:32:12.106 I/BtvMediaPlayer( 8541): [resetMediaPlayer] before MediaPlayer.release() 05-01 18:32:12.106 D/VODPlayer( 8541): showErrorAndExit() VODPlayerError.vodErrCode : R201 05-01 18:32:12.106 I/VODPlayer( 8541): onErrorVODPlay 05-01 18:32:12.106 I/ISQMSManager( 8541): openEvent() called. eventMessage : 22014 05-01 18:32:12.106 I/ISQMSManager( 8541): openEvent() mEventKey : 88 05-01 18:32:12.107 D/ISQMSManager( 8541): openEvent() removeKey : 86 05-01 18:32:12.107 V/MediaPlayer( 8541): resetDrmState: mDrmInfo=null mDrmProvisioningThread=null mPrepareDrmInProgress=false mActiveDrmScheme=false 05-01 18:32:12.107 I/VODPlayer( 8541): SvcVodCid : {AEC9362D-3C61-4F98-95B4-1C08FC43BF21} 05-01 18:32:12.107 V/MediaPlayer( 8541): cleanDrmObj: mDrmObj=null mDrmSessionId=null 05-01 18:32:12.107 D/ISQMSManager( 8541): setCheckSvcVodCid() called. vodCid : {AEC9362D-3C61-4F98-95B4-1C08FC43BF21} 05-01 18:32:12.107 I/VODPlayer( 8541): Vod1VodScsIp : agw.sk-iptv.com 05-01 18:32:12.107 D/ISQMSManager( 8541): setCheckVod1VodScsIp() called. key : 88, vodScsIp : agw.sk-iptv.com 05-01 18:32:12.107 I/VODPlayer( 8541): Vod1VodScsRt : 05-01 18:32:12.107 D/ISQMSManager( 8541): setCheckVod1VodScsRt() called. key : 88, vodScsRt : 05-01 18:32:12.107 D/TVSGtvPlayer( 3097): reset() >> begin 05-01 18:32:12.107 I/VODPlayer( 8541): Vod1VodDownIp : 0.0.0.0 05-01 18:32:12.107 D/ISQMSManager( 8541): setCheckVod1VodDownIp() called. key : 88, vodDownIp : 0.0.0.0 05-01 18:32:12.107 D/TVSGtvPlayer( 3097): onReset() >> begin 05-01 18:32:12.107 D/TVSGtvPlayer( 3097): onReset() << end 1 ms 05-01 18:32:12.107 D/TVSGtvPlayer( 3097): reset() << end 1 ms 05-01 18:32:12.107 D/TVSGtvPlayer( 3097): ~TVSGtvPlayer() >> begin 05-01 18:32:12.107 I/VODPlayer( 8541): Vod1VodDownRt : 00.044 05-01 18:32:12.107 D/TVSGtvPlayer( 3097): ~TVSGtvPlayer() << end 0 ms 05-01 18:32:12.107 D/ISQMSManager( 8541): setCheckVod1VodDownRt() called. key : 88, vodDownRt : 00.044 05-01 18:32:12.108 I/VODPlayer( 8541): Vod3VodContentName : 티라노 사우르스 렉스[] 05-01 18:32:12.108 D/ISQMSManager( 8541): setCheckVod3VodContentName() called. key : 88, vodContentName : 티라노 사우르스 렉스[] 05-01 18:32:12.108 I/VODPlayer( 8541): Vod3VodContentUrl : skbvod://cdn2.hanafostv.com:554/VOD/51/CS11010351/CE1000163529_0_TVHD_201903120315.ts.pac?ci={AEC9362D-3C61-4F98- 95B4-1C08FC43BF21}&oi=747aCE1000163529_0_TVHD_2019&op=4a0d1999030&rp=51 05-01 18:32:12.108 D/ISQMSManager( 8541): setCheckVod3VodContentUrl() called. key : 88, vodContentUrl : skbvod://cdn2.hanafostv.com:554/VOD/51/CS11010351/CE1000163529_0_TVHD _201903120315.ts.pac?ci={AEC9362D-3C61-4F98-95B4-1C08FC43BF21}&oi=747aCE1000163529_0_TVHD_2019&op=4a0d1999030&rp=51 05-01 18:32:12.108 D/TVSMediaServerImpl(19500): destroyPlayer() >> begin 05-01 18:32:12.108 I/VODPlayer( 8541): Vod4VodErr : MODE_FAIL 05-01 18:32:12.108 D/TVSMediaServerImpl(19500): player destroyed: id(7), total count(0) 05-01 18:32:12.108 D/ISQMSManager( 8541): setCheckVod4VodErr() called. key : 88, vod4_VOD_ERROR : MODE_FAIL 05-01 18:32:12.108 I/VODPlayer( 8541): Vod4Msg : R201 05-01 18:32:12.108 D/ISQMSManager( 8541): setCheckVod4Msg() called. key : 88, vodMessage : R201 05-01 18:32:12.108 I/ISQMSManager( 8541): sendEvent() called. key : 88, eventMessage : 22014 05-01 18:32:12.110 I/Avrcp ( 3371): Skipping update due to invalid playback state 05-01 18:32:12.110 E/bt_btif ( 3371): register_notification_rsp: Avrcp device is not connected, handle: 0x0 05-01 18:32:12.110 E/bt_btif ( 3371): register_notification_rsp: Avrcp device is not connected, handle: 0x0 05-01 18:32:12.110 I/ToastUtil( 8541): showThreadToast() called. descMain : 일시적인 장애로 서비스가 원활하지 않습니다. 05-01 18:32:12.110 I/ToastUtil( 8541): 잠시 후 다시 이용해주세요. R201, descSub : null 05-01 18:32:12.111 I/VODPlayer( 8541): onStopEvent Called 05-01 18:32:12.111 I/BtvMediaPlayer( 8541): stopMedia 05-01 18:32:12.111 I/BtvMediaPlayer( 8541): setState PlaybackState: Idle 05-01 18:32:12.111 I/BtvMediaPlayer( 8541): setPlayerCallback 05-01 18:32:12.111 D/VODPlayerUIControl( 8541): endVODPlay\|\| 05-01 18:32:12.111 D/TVSMediaServerImpl(19500): onNotifyMediaServerEvent() >> begin 05-01 18:32:12.111 D/KeyDelayUtil( 8541): VODPlayerUIControl#reset() called. 05-01 18:32:12.111 D/TVSMediaServerImpl(19500): onNotifyMediaServerEvent() << end 0 ms 05-01 18:32:12.111 D/UIControlManager( 8541): removeUIChangeListener() listener : com.skb.google.tv.player.VODPlayerUIControl@5c51900 05-01 18:32:12.111 I/TVSMMFClient( 4081): ===== tvs_mediaserver event callback: 0xad40a52c 05-01 18:32:12.112 I/TVSMMFClient( 4020): ===== tvs_mediaserver event callback: 0xa35b6e3c 05-01 18:32:12.112 D/ChannelMediaEventManager( 4081): Event : 10000, Source : 7, URI : skbvod://cdn2.hanafostv.com:554/VOD/51/CS11010351/CE1000163529_0_TVHD_201903120315.ts. pac?ci={AEC9362D-3C61-4F98-95B4-1C08FC43BF21}&oi=747aCE1000163529_0_TVHD_2019&op=4a0d1999030&rp=51 05-01 18:32:12.112 I/JNI_Settings( 4020): tvsMediaCallback(10000) 05-01 18:32:12.112 I/JNI_Settings( 4020): what(10000), extra(1000), data size(184) 05-01 18:32:12.112 D/DatacastingManager( 4081): [371] Event Type : 10000, Source : 7, Event Data(URI) : skbvod://cdn2.hanafostv.com:554/VOD/51/CS11010351/CE1000163529_0_TVHD _201903120315.ts.pac?ci={AEC9362D-3C61-4F98-95B4-1C08FC43BF21}&oi=747aCE1000163529_0_TVHD_2019&op=4a0d1999030&rp=51 05-01 18:32:12.112 D/MediaEventHandler( 4081): [114] Event Type : 10000, Source : 7, Event Data(URI) : skbvod://cdn2.hanafostv.com:554/VOD/51/CS11010351/CE1000163529_0_TVHD_ 201903120315.ts.pac?ci={AEC9362D-3C61-4F98-95B4-1C08FC43BF21}&oi=747aCE1000163529_0_TVHD_2019&op=4a0d1999030&rp=51 05-01 18:32:12.112 D/MediaEventHandler( 4081): [114] Ignore Event Type : 10000 05-01 18:32:12.112 I/VODPlayerUIControl( 8541): endRepeatMode() call. 05-01 18:32:12.112 D/VODPlayerUIControl( 8541): hidePlayBar\|\| 05-01 18:32:12.112 D/VODPlayerUIControl( 8541): hideScreenSearch\|\| 05-01 18:32:12.113 D/VODPlayerUIControl( 8541): Trick Mode\|false\| 05-01 18:32:12.113 I/VODPlayerUIControl( 8541): checkSpeedNSoundActivate() called. 05-01 18:32:12.113 I/VODPlayerUIControl( 8541): mCurrentTrickSpeed : 1000 05-01 18:32:12.113 D/VODPlayerUIControl( 8541): setZoneRepeatFloatingLayout. recording: false,playing: false 05-01 18:32:12.113 D/VODPlayerUIControl( 8541): hideSenceExpLayout 05-01 18:32:12.113 D/VODPlayerUIControl( 8541): isShownSubtitleInfo return false 05-01 18:32:12.113 I/VODPlayer( 8541): exitVODMode called 05-01 18:32:12.113 I/VODPlayer( 8541): closeVodSmallPopup() called 05-01 18:32:12.113 I/UIControlManager( 8541): processBundle() called. bundleType : COMMAND 05-01 18:32:12.113 D/UIControlManager( 8541): processBundle() commandBundleType : FRAGMENT_CLEAR_TYPE_LIST 05-01 18:32:12.113 D/UIControlManager( 8541): processBundle() currentFragment : null 05-01 18:32:12.113 I/FragmentControl( 8541): clearFragmentForTypeList() called. isAfterAdd : false, before LIST [] 05-01 18:32:12.113 I/FragmentControl( 8541): clearFragmentForTypeList() fragmentTypeList : [POPUP_LIVE_CONFIRM, POPUP_CORNER_COLLECTION_CONTROL, POPUP_VOD_SECTION_REPEAT, PO PUP_VOD_SECTION_REPEAT, POPUP_COMMON, POPUP_COMMON_FULL] 05-01 18:32:12.113 D/FragmentControl( 8541): clearFragmentForTypeList() remove size of FragmentList : 0 05-01 18:32:12.113 I/FragmentControl( 8541): correctionContainer() called. isShowFragment : false 05-01 18:32:12.114 D/FragmentControl( 8541): correctionContainer() BEFORE fullVisibility : GONE[8] 05-01 18:32:12.114 D/FragmentControl( 8541): correctionContainer() BEFORE popupVisibility : GONE[8] 05-01 18:32:12.114 I/FragmentControl( 8541): correctionContainer() currentFragment : null 05-01 18:32:12.114 D/FragmentControl( 8541): correctionContainer() currentFragment is null 05-01 18:32:12.114 D/FragmentControl( 8541): isUIVisibility() baseFragment is null 05-01 18:32:12.114 D/FragmentControl( 8541): isUIVisibility() isUIVisibility : false 05-01 18:32:12.114 D/FragmentControl( 8541): playerViewFocusLock() isFocusLock : false 05-01 18:32:12.114 D/FragmentControl( 8541): playerViewFocusLock() before playerDescendantFocusability : FOCUS_BEFORE_DESCENDANTS[131072] 05-01 18:32:12.114 D/FragmentControl( 8541): playerViewFocusLock() before multiViewDescendantFocusability : FOCUS_BEFORE_DESCENDANTS[131072] 05-01 18:32:12.114 D/FragmentControl( 8541): playerViewFocusLock() after playerDescendantFocusability : FOCUS_BEFORE_DESCENDANTS[131072] 05-01 18:32:12.114 D/FragmentControl( 8541): playerViewFocusLock() after multiViewDescendantFocusability : FOCUS_BEFORE_DESCENDANTS[131072] 05-01 18:32:12.114 D/FragmentControl( 8541): playerViewFocusLock() isBeforeFocusLock : false 05-01 18:32:12.114 D/FragmentControl( 8541): correctionContainer() AFTER fullVisibility : GONE[8] 05-01 18:32:12.114 D/FragmentControl( 8541): correctionContainer() AFTER popupVisibility : GONE[8] 05-01 18:32:12.114 D/FragmentControl( 8541): clearFragmentForTypeList() after LIST [] 05-01 18:32:12.114 I/KeyEventManager( 8541): processBundle() called. commandType : ALL_KEY_UNBLOCK 05-01 18:32:12.114 I/KeyEventManager( 8541): processBundle() called. commandType : NORMAL_KEY_UNBLOCK 05-01 18:32:12.114 I/KeyEventManager( 8541): processBundle() called. commandType : PATCH_KEY_UNBLOCK 05-01 18:32:12.114 I/KeyEventManager( 8541): processBundle() called. commandType : EMERGENCY_TVAPP_KEY_UNBLOCK 05-01 18:32:12.114 D/UIControlManager( 8541): checkDSMCCAndStartT_COM called 05-01 18:32:12.114 D/STBGlobal( 8541): getIsDSMCCDownLoad called mIsDSMCCDownLoad: false 05-01 18:32:12.119 D/STBAPIManager( 8541): getProperty() key : PROPERTY_CHILDREN_SEE_LIMIT_BTV_PLUS, result : 0 05-01 18:32:12.119 D/FragmentControl( 8541): getFullFragment() mFragmentList Count : 0 05-01 18:32:12.119 D/FragmentControl( 8541): isUIVisibilityForTcom() baseFragment is null 05-01 18:32:12.119 D/FragmentControl( 8541): isUIVisibilityForTcom() fullFragment is null 05-01 18:32:12.119 D/FragmentControl( 8541): isUIVisibilityForTcom() isUIVisibility : false 05-01 18:32:12.119 I/STBGlobal( 8541): getMainActivity() called 05-01 18:32:12.119 D/STBGlobal( 8541): getDSMCCURL called 05-01 18:32:12.119 I/STBGlobal( 8541): getMainActivity() called 05-01 18:32:12.119 D/STBGlobal( 8541): getIsRequestOKKeyFromTcom called mIsRequestOKKeyFromTcom: false 05-01 18:32:12.119 D/UIControlManager( 8541): checkDSMCCAndStartT_COM isUIVisibility(): false\|mainActivity.isShowAppDownTrigger : false \| isDSMCCDownLoad: false\| isShowAppDo wnTrigger: false\| isRequestOKKeyFromTcomd: false, isScrambled : false 05-01 18:32:12.119 D/FragmentControl( 8541): isUIVisibility() baseFragment is null 05-01 18:32:12.119 D/FragmentControl( 8541): isUIVisibility() isUIVisibility : false 05-01 18:32:12.119 D/VODPlayer( 8541): exitVODMode, isShowSynopsis : true, isUIVisibility : false 05-01 18:32:12.119 D/VodCSRequestInterface( 8541): setAndRequestSynop::Request synop Recover!!! 05-01 18:32:12.119 D/SendInterfaceManager( 8541): SendInterfaceManager getInstance 05-01 18:32:12.119 I/SendInterfaceManager( 8541): navigateSynopsisByContentId menuType : synopsis, episodeResolutionId : {AEC9362D-3C61-4F98-95B4-1C08FC43BF21}, checkAdult : false, fromCommerce : false 05-01 18:32:12.119 D/SendInterfaceManager( 8541): generateNavigateWebMenuObject 05-01 18:32:12.120 D/InterfaceInfoManager( 8541): getMenuNavigationWeb menuType : synopsis 05-01 18:32:12.120 D/InterfaceInfoManager( 8541): getMenuNavigationWeb SYNOPSIS searchType : 2 05-01 18:32:12.120 D/SendCommon( 8541): getSendJsonData 05-01 18:32:12.120 D/SendCommon( 8541): getSendJsonData mCommonObj : {"TYPE":"request","COMMAND":"MenuNavigationWeb","CONTENTS":"","DATA":{"menuType":"synopsis","extInfo":{" trackId":"","blockTrackId":"","uxReference":"","sessionId":"","search_type":"2","sris_id":"","epsd_id":"","epsd_rslu_id":"{AEC9362D-3C61-4F98-95B4-1C08FC43BF21}","seeingPath ":"99","title":"","isKidsContents":"N","adultCheck":false,"fromCommerce":false,"showGnbNavigation":"N","currentPlayState":"6"}}} 05-01 18:32:12.120 I/G2TvFragment( 8541): isFragmentHidden() isFragmentHidden : true 05-01 18:32:12.120 I/UIControlManager( 8541): processBundle() called. bundleType : TRANSITION 05-01 18:32:12.120 D/PlayerManager( 8541): Play Request [STOP_PIP] 05-01 18:32:12.120 I/UIControlManager( 8541): processBundle() called. bundleType : PLAY_CONTENT 05-01 18:32:12.120 D/UIControlManager( 8541): processBundle() add fragmentType : G2_WEB 05-01 18:32:12.120 D/UIControlManager( 8541): processBundle() currentFragment : null 05-01 18:32:12.120 I/FragmentControl( 8541): addFragment() called. add FragmentType : G2_WEB 05-01 18:32:12.120 I/FragmentControl( 8541): setLastFocusPlayerView() called 05-01 18:32:12.120 D/FragmentControl( 8541): setLastFocusPlayerView() findFocus is null 05-01 18:32:12.120 I/FragmentControl( 8541): isPossibleForBeforeBooting() called. isBootCompleted : true 05-01 18:32:12.121 D/STBAPIManager( 8541): getProperty() key : PROPERTY_CHILDREN_SEE_LIMIT_BTV_PLUS, result : 0 05-01 18:32:12.122 I/FragmentControl( 8541): isPossibleForChildrenSeeLimit() called. isChildrenSeeLimit : false 05-01 18:32:12.122 I/FragmentControl( 8541): isPossibleForNetwork() called. isNetworkKeyBlock : false 05-01 18:32:12.122 I/FragmentControl( 8541): isPossibleForNetwork() called. isBootCompleted : true 05-01 18:32:12.122 I/FragmentControl( 8541): isExistFragment() called. fragmentType : POPUP_NOACCESS_SERVICE, isExist : false 05-01 18:32:12.122 I/FragmentControl( 8541): isPossibleForScramble() called. isPossibleForScramble : false 05-01 18:32:12.122 I/FragmentControl( 8541): isExistFragment() called. fragmentType : POPUP_REMOTE_UPDATE, isExist : false 05-01 18:32:12.122 I/FragmentControl( 8541): isPossibleForRemoteUpdate() called. isPossibleForRemoteUpdate : false 05-01 18:32:12.122 D/FragmentControl( 8541): addFragment() isEqualCurrentFragmentType : false 05-01 18:32:12.122 D/FragmentControl( 8541): addFragment() getExistSingletonFragment : G2TvFragment{25f6f43 #1 id=0x7f08049e} 05-01 18:32:12.122 D/FragmentControl( 8541): addFragment() remove size of popupFragmentList : 0 05-01 18:32:12.122 D/FragmentControl( 8541): processHistoryBack() mFragmentList Count : 0 05-01 18:32:12.122 D/FragmentControl( 8541): addFragment() currentFragment : null 05-01 18:32:12.122 I/G2TvFragment( 8541): setFragmentHidden() isFragmentHidden : false 05-01 18:32:12.122 D/FragmentControl( 8541): addFragment() ADD : after LIST [G2TvFragment{25f6f43 #1 id=0x7f08049e}], NETWORK LIST : [] 05-01 18:32:12.122 I/FragmentControl( 8541): correctionContainer() called. isShowFragment : true 05-01 18:32:12.122 D/FragmentControl( 8541): correctionContainer() BEFORE fullVisibility : GONE[8] 05-01 18:32:12.122 D/FragmentControl( 8541): correctionContainer() BEFORE popupVisibility : GONE[8] 05-01 18:32:12.122 I/FragmentControl( 8541): correctionContainer() currentFragment : G2TvFragment{25f6f43 #1 id=0x7f08049e} 05-01 18:32:12.123 D/FragmentControl( 8541): isUIVisibility() baseFragment : G2TvFragment{25f6f43 #1 id=0x7f08049e} 05-01 18:32:12.123 I/G2TvFragment( 8541): isFragmentHidden() isFragmentHidden : false 05-01 18:32:12.123 D/FragmentControl( 8541): isUIVisibility() G2TvFragment is VISIBLE ? true 05-01 18:32:12.123 D/FragmentControl( 8541): isUIVisibility() isHidden : false 05-01 18:32:12.123 D/FragmentControl( 8541): isUIVisibility() isUIVisibility : true 05-01 18:32:12.123 D/FragmentControl( 8541): playerViewFocusLock() isFocusLock : true 05-01 18:32:12.123 D/FragmentControl( 8541): playerViewFocusLock() before playerDescendantFocusability : FOCUS_BEFORE_DESCENDANTS[131072] 05-01 18:32:12.123 D/FragmentControl( 8541): playerViewFocusLock() before multiViewDescendantFocusability : FOCUS_BEFORE_DESCENDANTS[131072] 05-01 18:32:12.123 D/FragmentControl( 8541): playerViewFocusLock() after playerDescendantFocusability : FOCUS_BLOCK_DESCENDANTS[393216] 05-01 18:32:12.123 D/FragmentControl( 8541): playerViewFocusLock() after multiViewDescendantFocusability : FOCUS_BLOCK_DESCENDANTS[393216] 05-01 18:32:12.123 D/FragmentControl( 8541): playerViewFocusLock() isBeforeFocusLock : false 05-01 18:32:12.123 D/FragmentControl( 8541): correctionContainer() AFTER fullVisibility : VISIBLE[0] 05-01 18:32:12.123 D/FragmentControl( 8541): correctionContainer() AFTER popupVisibility : GONE[8] 05-01 18:32:12.123 I/G2TvFragment( 8541): getTransitionBundle() called 05-01 18:32:12.123 I/KeyEventManager( 8541): processBundle() called. commandType : ALL_KEY_UNBLOCK 05-01 18:32:12.123 I/KeyEventManager( 8541): processBundle() called. commandType : PATCH_KEY_UNBLOCK 05-01 18:32:12.123 I/KeyEventManager( 8541): processBundle() called. commandType : NORMAL_KEY_UNBLOCK 05-01 18:32:12.123 I/KeyEventManager( 8541): processBundle() called. commandType : SCRAMBLE_KEY_UNBLOCK 05-01 18:32:12.123 I/KeyEventManager( 8541): processBundle() called. commandType : REMOTE_KEY_UNBLOCK 05-01 18:32:12.123 I/KeyEventManager( 8541): processBundle() called. commandType : WELCOME_KIT_KEY_UNBLOCK 05-01 18:32:12.123 I/KeyEventManager( 8541): processBundle() called. commandType : EMERGENCY_TVAPP_KEY_UNBLOCK 05-01 18:32:12.123 I/G2TvFragment( 8541): isFragmentHidden() isFragmentHidden : false 05-01 18:32:12.124 D/STBAPIManager( 8541): getProperty() key : PROPERTY_AUDIO_SETTING, result : 1 05-01 18:32:12.124 D/STBFunctionManager( 4020): getSTBFunctionInstance() 05-01 18:32:12.124 D/SimpleAudioManager( 8541): isMute() isMute : false 05-01 18:32:12.125 D/AudioService( 3253): setStreamVolume(stream=3, index=8, calling=com.skb.tv) 05-01 18:32:12.125 D/AudioService( 3253): getAudioOutput() audioOutputMode: 0 05-01 18:32:12.125 D/AudioService( 3253): getAudioMode() value : 0, audioMode : pcm , mIsSleep: false 05-01 18:32:12.129 D/vol.VolumeDialogControl( 3406): onReceive VOLUME_CHANGED_ACTION stream=12 level=8 oldLevel=16 05-01 18:32:12.129 D/vol.TVSVolumeDialogImpl( 3406): updateVolumeRowH() row.stream=3, row.showUI=false 05-01 18:32:12.129 D/vol.TVSVolumeDialogImpl( 3406): updateVolumeRowH() ss.muted=false, vlevel=16 05-01 18:32:12.129 D/vol.TVSVolumeDialogImpl( 3406): getAudioMode() value : 0, audioMode : pcm , mIsSleep: false 05-01 18:32:12.129 D/vol.TVSVolumeDialogImpl( 3406): showVolumeLevel : muted=false, max=32, volume_level=16, visible=false, fromKey=false, showDDSNoti=false, uiMaxVolume=32, bAudioMode=0 05-01 18:32:12.130 D/vol.VolumeDialogControl( 3406): onReceive VOLUME_CHANGED_ACTION stream=11 level=8 oldLevel=16 05-01 18:32:12.131 D/vol.TVSVolumeDialogImpl( 3406): updateVolumeRowH() row.stream=3, row.showUI=false 05-01 18:32:12.131 D/vol.TVSVolumeDialogImpl( 3406): updateVolumeRowH() ss.muted=false, vlevel=16 05-01 18:32:12.131 D/vol.TVSVolumeDialogImpl( 3406): getAudioMode() value : 0, audioMode : pcm , mIsSleep: false 05-01 18:32:12.131 D/vol.TVSVolumeDialogImpl( 3406): showVolumeLevel : muted=false, max=32, volume_level=16, visible=false, fromKey=false, showDDSNoti=false, uiMaxVolume=32, bAudioMode=0 05-01 18:32:12.132 D/vol.VolumeDialogControl( 3406): onReceive VOLUME_CHANGED_ACTION stream=10 level=4 oldLevel=8 05-01 18:32:12.132 D/vol.VolumeDialogControl( 3406): onReceive VOLUME_CHANGED_ACTION stream=9 level=4 oldLevel=8 05-01 18:32:12.132 D/vol.TVSVolumeDialogImpl( 3406): updateVolumeRowH() row.stream=3, row.showUI=false 05-01 18:32:12.133 D/vol.TVSVolumeDialogImpl( 3406): updateVolumeRowH() ss.muted=false, vlevel=16 05-01 18:32:12.133 D/vol.TVSVolumeDialogImpl( 3406): getAudioMode() value : 0, audioMode : pcm , mIsSleep: false 05-01 18:32:12.133 D/vol.TVSVolumeDialogImpl( 3406): showVolumeLevel : muted=false, max=32, volume_level=16, visible=false, fromKey=false, showDDSNoti=false, uiMaxVolume=32, bAudioMode=0 05-01 18:32:12.135 D/vol.VolumeDialogControl( 3406): onReceive VOLUME_CHANGED_ACTION stream=9 level=4 oldLevel=8 05-01 18:32:12.135 D/vol.VolumeDialogControl( 3406): onReceive VOLUME_CHANGED_ACTION stream=8 level=4 oldLevel=8 05-01 18:32:12.136 D/vol.VolumeDialogControl( 3406): onReceive VOLUME_CHANGED_ACTION stream=7 level=2 oldLevel=4 05-01 18:32:12.136 D/TVSAudioManager(19500): [removeAudioStatusListener] listener=0xa8cbb3c4, pid=19516 05-01 18:32:12.136 D/HalPlayer(19500): close() >> begin 05-01 18:32:12.137 D/AmlHalPlayer(19500): close() >> begin 05-01 18:32:12.137 D/AmlVideo(19500): close() >> begin 05-01 18:32:12.137 D/AmlVideo(19500): close() << end 0 ms 05-01 18:32:12.137 D/AmlVideo(19500): ~AmlVideo() >> begin 05-01 18:32:12.137 D/AmlVideo(19500): ~AmlVideo() << end 0 ms 05-01 18:32:12.137 D/AmlAudio(19500): close() >> begin 05-01 18:32:12.137 D/AmlAudio(19500): close() << end 0 ms 05-01 18:32:12.137 D/AmlAudio(19500): ~AmlAudio() >> begin 05-01 18:32:12.137 D/AmlAudio(19500): ~AmlAudio() << end 0 ms 05-01 18:32:12.137 D/AmlHalPlayer(19500): Destroy avplay: 0xa8cee000, mZorder=-1 05-01 18:32:12.137 E/Matrix.Framework(19500): [29.888259 18:32:12.137406 0.000000] OS: 11 (Segmentation fault) code: 1(SEGV_MAPERR) @ 0x000005 05-01 18:32:12.138 D/vol.VolumeDialogControl( 3406): onReceive VOLUME_CHANGED_ACTION stream=6 level=4 oldLevel=8 05-01 18:32:12.138 I/vol.Events( 3406): writeEvent level_changed STREAM_BLUETOOTH_SCO 4 05-01 18:32:12.139 D/vol.TVSVolumeDialogImpl( 3406): updateVolumeRowH() row.stream=3, row.showUI=false 05-01 18:32:12.139 D/vol.TVSVolumeDialogImpl( 3406): updateVolumeRowH() ss.muted=false, vlevel=16 05-01 18:32:12.139 D/vol.TVSVolumeDialogImpl( 3406): getAudioMode() value : 0, audioMode : pcm , mIsSleep: false 05-01 18:32:12.139 D/vol.TVSVolumeDialogImpl( 3406): showVolumeLevel : muted=false, max=32, volume_level=16, visible=false, fromKey=false, showDDSNoti=false, uiMaxVolume=32, bAudioMode=0 05-01 18:32:12.139 D/vol.VolumeDialogControl( 3406): onReceive VOLUME_CHANGED_ACTION stream=5 level=2 oldLevel=4 05-01 18:32:12.141 D/vol.TVSVolumeDialogImpl( 3406): updateVolumeRowH() row.stream=3, row.showUI=false 05-01 18:32:12.141 D/vol.TVSVolumeDialogImpl( 3406): updateVolumeRowH() ss.muted=false, vlevel=16 05-01 18:32:12.141 D/vol.TVSVolumeDialogImpl( 3406): getAudioMode() value : 0, audioMode : pcm , mIsSleep: false 05-01 18:32:12.141 D/vol.TVSVolumeDialogImpl( 3406): showVolumeLevel : muted=false, max=32, volume_level=16, visible=false, fromKey=false, showDDSNoti=false, uiMaxVolume=32, bAudioMode=0 05-01 18:32:12.142 D/vol.VolumeDialogControl( 3406): onReceive VOLUME_CHANGED_ACTION stream=0 level=1 oldLevel=3 05-01 18:32:12.142 I/vol.Events( 3406): writeEvent level_changed STREAM_VOICE_CALL 1 05-01 18:32:12.142 D/vol.TVSVolumeDialogImpl( 3406): updateVolumeRowH() row.stream=3, row.showUI=false 05-01 18:32:12.142 D/vol.TVSVolumeDialogImpl( 3406): updateVolumeRowH() ss.muted=false, vlevel=16 05-01 18:32:12.142 D/vol.TVSVolumeDialogImpl( 3406): getAudioMode() value : 0, audioMode : pcm , mIsSleep: false 05-01 18:32:12.142 D/vol.TVSVolumeDialogImpl( 3406): showVolumeLevel : muted=false, max=32, volume_level=16, visible=false, fromKey=false, showDDSNoti=false, uiMaxVolume=32, bAudioMode=0 05-01 18:32:12.144 D/vol.TVSVolumeDialogImpl( 3406): updateVolumeRowH() row.stream=3, row.showUI=false 05-01 18:32:12.144 D/vol.TVSVolumeDialogImpl( 3406): updateVolumeRowH() ss.muted=false, vlevel=16 05-01 18:32:12.144 D/vol.TVSVolumeDialogImpl( 3406): getAudioMode() value : 0, audioMode : pcm , mIsSleep: false 05-01 18:32:12.144 D/vol.TVSVolumeDialogImpl( 3406): showVolumeLevel : muted=false, max=32, volume_level=16, visible=false, fromKey=false, showDDSNoti=false, uiMaxVolume=32, bAudioMode=0 05-01 18:32:12.144 I/CompanionProperty( 8541): setCompanionPropertyInt() called 05-01 18:32:12.144 D/vol.VolumeDialogControl.VC( 3406): volumeChanged STREAM_MUSIC 05-01 18:32:12.144 D/CompanionProperty( 8541): setCompanionPropertyInt() end 05-01 18:32:12.144 D/UIControlManager( 8541): setCompanion() WatchingTv : false 05-01 18:32:12.144 D/PlayerManager( 8541): Play Request [PROMOTION_HOME] 05-01 18:32:12.144 D/STBGlobal( 8541): setIsDSMCCFirstFlag called isDSMCCFirstFlag: false 05-01 18:32:12.145 D/STBGlobal( 8541): setIsDSMCCDownLoad called isDSMCCDownLoad: false 05-01 18:32:12.145 D/STBGlobal( 8541): setDSMCCURL called url: null 05-01 18:32:12.145 I/PlayerManager( 8541): onPlayerTypeChange() called. mediaType : PROMOTION_HOME 05-01 18:32:12.145 D/Contextual( 8541): makeUri \| {"from":"com.skb.tv","domain":"vod","extras":{"state":"end","contents_name":"티라노 사우르스 렉스"}} 05-01 18:32:12.145 W/ContextImpl( 8541): Calling a method in the system process without a qualified user: android.app.ContextImpl.sendBroadcast:1005 android.content.ContextW rapper.sendBroadcast:444 com.skb.google.tv.global.contextual.Contextual.sendBroadcast:84 com.skb.google.tv.global.contextual.Contextual.sendStatus:27 com.skb.google.tv.playe r.PlayerManager$6.onPlayerTypeChange:315 05-01 18:32:12.145 D/vol.TVSVolumeDialogImpl( 3406): updateVolumeRowH() row.stream=3, row.showUI=false 05-01 18:32:12.145 D/vol.TVSVolumeDialogImpl( 3406): updateVolumeRowH() ss.muted=false, vlevel=16 05-01 18:32:12.146 D/vol.TVSVolumeDialogImpl( 3406): getAudioMode() value : 0, audioMode : pcm , mIsSleep: false 05-01 18:32:12.146 D/vol.TVSVolumeDialogImpl( 3406): showVolumeLevel : muted=false, max=32, volume_level=16, visible=false, fromKey=false, showDDSNoti=false, uiMaxVolume=32, bAudioMode=0 05-01 18:32:12.146 D/vol.VolumeDialogControl( 3406): onVolumeChangedW showUI=false, fromKey=false, showDDSNoti=false 05-01 18:32:12.147 E/ActivityManager( 3253): java.lang.Throwable 05-01 18:32:12.147 E/ActivityManager( 3253): at com.android.server.am.ActivityManagerService.checkBroadcastFromSystem(ActivityManagerService.java:21228) 05-01 18:32:12.147 E/ActivityManager( 3253): at com.android.server.am.ActivityManagerService.broadcastIntentLocked(ActivityManagerService.java:21832) 05-01 18:32:12.147 E/ActivityManager( 3253): at com.android.server.am.ActivityManagerService.broadcastIntent(ActivityManagerService.java:21974) 05-01 18:32:12.147 E/ActivityManager( 3253): at android.app.IActivityManager$Stub.onTransact$broadcastIntent$(IActivityManager.java:10171) 05-01 18:32:12.147 E/ActivityManager( 3253): at android.app.IActivityManager$Stub.onTransact(IActivityManager.java:167) 05-01 18:32:12.147 E/ActivityManager( 3253): at com.android.server.am.ActivityManagerService.onTransact(ActivityManagerService.java:3299) 05-01 18:32:12.147 E/ActivityManager( 3253): at android.os.Binder.execTransact(Binder.java:731) 05-01 18:32:12.147 I/vol.Events( 3406): writeEvent level_changed STREAM_MUSIC 8 05-01 18:32:12.147 D/vol.TVSVolumeDialogImpl( 3406): updateVolumeRowH() row.stream=3, row.showUI=false 05-01 18:32:12.147 D/vol.TVSVolumeDialogImpl( 3406): updateVolumeRowH() ss.muted=false, vlevel=16 05-01 18:32:12.147 D/vol.TVSVolumeDialogImpl( 3406): getAudioMode() value : 0, audioMode : pcm , mIsSleep: false 05-01 18:32:12.147 D/vol.TVSVolumeDialogImpl( 3406): showVolumeLevel : muted=false, max=32, volume_level=16, visible=false, fromKey=false, showDDSNoti=false, uiMaxVolume=32, bAudioMode=0 05-01 18:32:12.148 D/vol.TVSVolumeDialogImpl( 3406): updateVolumeRowH() row.stream=3, row.showUI=false 05-01 18:32:12.148 D/vol.TVSVolumeDialogImpl( 3406): updateVolumeRowH() ss.muted=false, vlevel=8 05-01 18:32:12.148 D/vol.TVSVolumeDialogImpl( 3406): getAudioMode() value : 0, audioMode : pcm , mIsSleep: false 05-01 18:32:12.148 D/vol.TVSVolumeDialogImpl( 3406): showVolumeLevel : muted=false, max=32, volume_level=8, visible=false, fromKey=false, showDDSNoti=false, uiMaxVolume=32, bAudioMode=0 05-01 18:32:12.148 I/VODPlayer( 8541): onStopEvent Called 05-01 18:32:12.148 I/BtvMediaPlayer( 8541): stopMedia 05-01 18:32:12.148 I/BtvMediaPlayer( 8541): setState PlaybackState: Idle 05-01 18:32:12.148 I/BtvMediaPlayer( 8541): setPlayerCallback 05-01 18:32:12.148 D/VODPlayerUIControl( 8541): endVODPlay\|\| 05-01 18:32:12.149 D/KeyDelayUtil( 8541): VODPlayerUIControl#reset() called. 05-01 18:32:12.149 D/UIControlManager( 8541): removeUIChangeListener() listener : com.skb.google.tv.player.VODPlayerUIControl@5c51900 05-01 18:32:12.149 D/vol.VolumeDialogControl( 3406): onReceive VOLUME_CHANGED_ACTION stream=3 level=8 oldLevel=16 05-01 18:32:12.149 I/VODPlayerUIControl( 8541): endRepeatMode() call. 05-01 18:32:12.149 D/VODPlayerUIControl( 8541): hidePlayBar\|\| 05-01 18:32:12.149 D/VODPlayerUIControl( 8541): hideScreenSearch\|\| 05-01 18:32:12.149 D/VODPlayerUIControl( 8541): Trick Mode\|false\| 05-01 18:32:12.149 I/VODPlayerUIControl( 8541): checkSpeedNSoundActivate() called. 05-01 18:32:12.149 I/VODPlayerUIControl( 8541): mCurrentTrickSpeed : 1000 05-01 18:32:12.149 D/VODPlayerUIControl( 8541): setZoneRepeatFloatingLayout. recording: false,playing: false 05-01 18:32:12.149 D/VODPlayerUIControl( 8541): hideSenceExpLayout 05-01 18:32:12.149 D/VODPlayerUIControl( 8541): isShownSubtitleInfo return false 05-01 18:32:12.150 I/STBGlobal( 8541): setEpisodeResolutionID : null 05-01 18:32:12.150 I/STBGlobal( 8541): setSeriesID : null 05-01 18:32:12.150 I/STBGlobal( 8541): setEpisodeID : null 05-01 18:32:12.150 I/VODPlayerUIControl( 8541): endWatermark 05-01 18:32:12.150 I/VODPlayerUIControl( 8541): hideWatermark 05-01 18:32:12.150 D/PlayerManager( 8541): setPlayerLayout called MediaType: PROMOTION_HOME 05-01 18:32:12.150 D/NoticController( 8541): getNoticeList call mNoticeList.size(): 0 05-01 18:32:12.150 I/TargetAD( 8541): State: 3 05-01 18:32:12.150 W/ContextImpl( 8541): Calling a method in the system process without a qualified user: android.app.ContextImpl.sendBroadcast:1005 android.content.ContextW rapper.sendBroadcast:444 com.skb.google.tv.player.PlayerManager.setState:1712 com.skb.google.tv.player.PlayerManager$6.onPlayerTypeChange:495 com.skb.google.tv.player.Promot ionPlayer.onPlayBundle:204 05-01 18:32:12.151 E/ActivityManager( 3253): Sending non-protected broadcast tv.anypoint.STATE_CHANGE from system 8541:com.skb.tv/1000 pkg com.skb.tv 05-01 18:32:12.151 E/ActivityManager( 3253): java.lang.Throwable 05-01 18:32:12.151 E/ActivityManager( 3253): at com.android.server.am.ActivityManagerService.checkBroadcastFromSystem(ActivityManagerService.java:21228) 05-01 18:32:12.151 E/ActivityManager( 3253): at com.android.server.am.ActivityManagerService.broadcastIntentLocked(ActivityManagerService.java:21739) 05-01 18:32:12.151 E/ActivityManager( 3253): at com.android.server.am.ActivityManagerService.broadcastIntent(ActivityManagerService.java:21974) 05-01 18:32:12.151 E/ActivityManager( 3253): at android.app.IActivityManager$Stub.onTransact$broadcastIntent$(IActivityManager.java:10171) 05-01 18:32:12.151 E/ActivityManager( 3253): at android.app.IActivityManager$Stub.onTransact(IActivityManager.java:167) 05-01 18:32:12.151 E/ActivityManager( 3253): at com.android.server.am.ActivityManagerService.onTransact(ActivityManagerService.java:3299) 05-01 18:32:12.151 E/ActivityManager( 3253): at android.os.Binder.execTransact(Binder.java:731) 05-01 18:32:12.153 E/ActivityManager( 3253): Sending non-protected broadcast tv.anypoint.STATE_CHANGE from system 8541:com.skb.tv/1000 pkg com.skb.tv 05-01 18:32:12.153 E/ActivityManager( 3253): java.lang.Throwable 05-01 18:32:12.153 E/ActivityManager( 3253): at com.android.server.am.ActivityManagerService.checkBroadcastFromSystem(ActivityManagerService.java:21228) 05-01 18:32:12.153 E/ActivityManager( 3253): at com.android.server.am.ActivityManagerService.broadcastIntentLocked(ActivityManagerService.java:21832) 05-01 18:32:12.153 E/ActivityManager( 3253): at com.android.server.am.ActivityManagerService.broadcastIntent(ActivityManagerService.java:21974) 05-01 18:32:12.153 E/ActivityManager( 3253): at android.app.IActivityManager$Stub.onTransact$broadcastIntent$(IActivityManager.java:10171) 05-01 18:32:12.153 E/ActivityManager( 3253): at android.app.IActivityManager$Stub.onTransact(IActivityManager.java:167) 05-01 18:32:12.153 E/ActivityManager( 3253): at com.android.server.am.ActivityManagerService.onTransact(ActivityManagerService.java:3299) 05-01 18:32:12.153 E/ActivityManager( 3253): at android.os.Binder.execTransact(Binder.java:731) 05-01 18:32:12.155 D/Contextual( 8541): makeUri \| {"from":"com.skb.tv","domain":"oap_player","extras":{"state":"start"}} 05-01 18:32:12.155 W/ContextImpl( 8541): Calling a method in the system process without a qualified user: android.app.ContextImpl.sendBroadcast:1005 android.content.ContextW rapper.sendBroadcast:444 com.skb.google.tv.global.contextual.Contextual.sendBroadcast:84 com.skb.google.tv.global.contextual.Contextual.sendStatus:27 com.skb.google.tv.playe r.PlayerManager$6.onPlayerTypeChange:498 05-01 18:32:12.156 E/ActivityManager( 3253): java.lang.Throwable 05-01 18:32:12.156 E/ActivityManager( 3253): at com.android.server.am.ActivityManagerService.checkBroadcastFromSystem(ActivityManagerService.java:21228) 05-01 18:32:12.156 E/ActivityManager( 3253): at com.android.server.am.ActivityManagerService.broadcastIntentLocked(ActivityManagerService.java:21832) 05-01 18:32:12.156 E/ActivityManager( 3253): at com.android.server.am.ActivityManagerService.broadcastIntent(ActivityManagerService.java:21974) 05-01 18:32:12.156 E/ActivityManager( 3253): at android.app.IActivityManager$Stub.onTransact$broadcastIntent$(IActivityManager.java:10171) 05-01 18:32:12.156 E/ActivityManager( 3253): at android.app.IActivityManager$Stub.onTransact(IActivityManager.java:167) 05-01 18:32:12.156 E/ActivityManager( 3253): at com.android.server.am.ActivityManagerService.onTransact(ActivityManagerService.java:3299) 05-01 18:32:12.156 E/ActivityManager( 3253): at android.os.Binder.execTransact(Binder.java:731) 05-01 18:32:12.158 I/MenuNaviManager( 8541): processEvent() called. 05-01 18:32:12.158 D/MenuNaviManager( 8541): processEvent() mLogStringBuilder length : 367 05-01 18:32:12.158 D/MenuNaviManager( 8541): sequenceNum : 339 05-01 18:32:12.158 D/MenuNaviManager( 8541): processEvent() data : ,N339^20190501183212.158^^HOHM^^^^^^^^^^^^^ 05-01 18:32:12.158 D/MenuNaviManager( 8541): MNLog length : 410 05-01 18:32:12.158 E/HomeAdManager( 8541): removeKeyobserver() mIsAddKeyObserver is false 05-01 18:32:12.158 I/STBGlobal( 8541): setAppMode() called. app_MODE : APP_MODE_HOM 05-01 18:32:12.158 D/ISQMSManager( 8541): setCommonSvcMode() called. scv_MODE : MODE_HOM 05-01 18:32:12.158 I/ISQMSManager( 8541): closeEvent() called. eventMessage : 21009 05-01 18:32:12.158 D/SendInterfaceManager( 8541): SendInterfaceManager getInstance 05-01 18:32:12.158 D/SendInterfaceManager( 8541): sendChangePlayInfo 05-01 18:32:12.158 D/InterfaceInfoManager( 8541): getChangePlayInfo playType : ETC, episodeID : 05-01 18:32:12.159 D/SendCommon( 8541): getSendJsonData 05-01 18:32:12.159 D/SendCommon( 8541): getSendJsonData mCommonObj : {"TYPE":"notify","COMMAND":"ChangePlayInfo","CONTENTS":"","DATA":{"isPlayType":"ETC","contentId":""}} 05-01 18:32:12.159 I/UIControlManager( 8541): processBundle() called. bundleType : COMMAND 05-01 18:32:12.159 D/UIControlManager( 8541): processBundle() commandBundleType : SEND_JSON_DATA 05-01 18:32:12.159 D/UIControlManager( 8541): processBundle() currentFragment : G2TvFragment{25f6f43 #1 id=0x7f08049e} 05-01 18:32:12.159 D/G2TvFragment( 8541): onReceiveBundle 05-01 18:32:12.159 D/G2TvFragment( 8541): processCommandBundle 05-01 18:32:12.159 D/G2TvFragment( 8541): processCommandBundle COMMAND_CODE_SEND_JAVASCRIPT 05-01 18:32:12.159 D/G2TvFragment( 8541): sendJsonData 05-01 18:32:12.159 D/G2TvFragment( 8541): sendJsonData script core.receiveMessageFromNative({"TYPE":"notify","COMMAND":"ChangePlayInfo","CONTENTS":"","DATA":{"isPlayType":"E TC","contentId":""}}); 05-01 18:32:12.159 D/G2TvFragment( 8541): processCommandBundle processed : true 05-01 18:32:12.159 D/NoticController( 8541): getNoticeList call mNoticeList.size(): 0 05-01 18:32:12.159 I/MainActivity( 8541): hideNotice() called 05-01 18:32:12.159 D/NoticeUIControll( 8541): hideNotice() called 05-01 18:32:12.159 I/TVSAudioManager(19500): checkAudioStatus(): android::AudioSystem::setParameters("btv_playback=off") 05-01 18:32:12.159 I/MainActivity( 8541): checkNotice() called 05-01 18:32:12.159 D/NoticController( 8541): getNoticeList call mNoticeList.size(): 0 05-01 18:32:12.159 D/NoticController( 8541): getNoticeList call mNoticeList.size(): 0 05-01 18:32:12.159 D/NoticeUIControll( 8541): hideNotice() called 05-01 18:32:12.159 I/HomeAdManager( 8541): startHomePromotion() called 05-01 18:32:12.159 I/HomeAdManager( 8541): endHomePromotion() called 05-01 18:32:12.159 I/HomeAdManager( 8541): hidePopup() called 05-01 18:32:12.159 I/FragmentControl( 8541): isExistFragment() called. fragmentType : POPUP_AD_IMAGE, isExist : false 05-01 18:32:12.159 D/HomeAdManager( 8541): hidePopup() isExsit : false 05-01 18:32:12.159 D/HomeAdManager( 8541): startHomePromotion() before PlayerError : false, RtspErrorCount : 4 05-01 18:32:12.159 I/HomeAdManager( 8541): getServerDefaultAdFileListInfo() called 05-01 18:32:12.160 I/HomeAdManager( 8541): getHomeDefaultAdFileListInfo() called 05-01 18:32:12.161 I/TVSAudioManager(19500): dump(): devices=0x00000400, mAudioOutputType=0, mBtvPlayback=0 05-01 18:32:12.161 D/HomeAdManager( 8541): getHomeDefaultAdFileListInfo() defaultHomeAdFile : /data/skb_data/DATA/adv/ID_main.ts 05-01 18:32:12.161 D/HomeAdManager( 8541): getHomeDefaultAdFileListInfo() defaultHomeAdFile : /data/skb_data/DATA/adv/ID_Logo_Object.ts 05-01 18:32:12.161 D/HomeAdManager( 8541): getHomeDefaultAdFileListInfo() defaultHomeAdFile : /data/skb_data/DATA/adv/adv.v181123.ok 05-01 18:32:12.161 I/HomeAdManager( 8541): getHomeAdInfo() called 05-01 18:32:12.161 I/HomeAdManager( 8541): searchPlayHomeAdInfo() called. searchIndex : 0 / homeAdIndex : 2 05-01 18:32:12.161 D/HomeAdManager( 8541): searchPlayHomeAdInfo() mLastStatus.m_oErrorInteger : 0 05-01 18:32:12.161 I/HomeAdManager( 8541): getFile() called 05-01 18:32:12.161 D/HomeAdManager( 8541): getFile() m_oPlayType : DOWNLOAD_AND_PLAY 05-01 18:32:12.161 D/HomeAdManager( 8541): getFile() FileLocation : /data/skb_data/DATA/adv/cache/O0000001033_190425190526.ts 05-01 18:32:12.161 D/PromotionPlayer( 8541): Promotion onPlayBundle [/data/skb_data/DATA/adv/cache/O0000001033_190425190526.ts] 05-01 18:32:12.161 I/BtvMediaPlayer( 8541): playMedia ContentURI: skbfile:///data/skb_data/DATA/adv/cache/O0000001033_190425190526.ts, TimeInfo: falseMilliSec : 0 05-01 18:32:12.161 I/BtvMediaPlayer( 8541): setState PlaybackState: Idle 05-01 18:32:12.161 I/BtvMediaPlayer( 8541): setPlayerCallback 05-01 18:32:12.161 I/HomeAdManager( 8541): showTriggerMenu() called 05-01 18:32:12.161 D/HomeAdManager( 8541): showTriggerMenu() homeAdInfo.m_oAdLinkType : 503 05-01 18:32:12.161 I/HomeAdManager( 8541): showShortcut() called 05-01 18:32:12.161 I/UIControlManager( 8541): processBundle() called. bundleType : PLAY_CONTENT 05-01 18:32:12.162 W/MediaPlayer( 8541): mediaplayer went away with unhandled events 05-01 18:32:12.162 W/MediaPlayer( 8541): mediaplayer went away with unhandled events 05-01 18:32:12.162 I/DCSendManager( 8541): Start Time is zero 05-01 18:32:12.167 E/Matrix.Framework(19500): [29.918263 18:32:12.167410 0.030004] at /system/lib/libutils.so.0xa9dec854: _ZNK7android12SortedVectorINS_16key_value_pair_tI NS_7String8ES2_EEE10do_compareEPKvS6_+0x1d 05-01 18:32:12.167 I/BtvMediaPlayer( 8541): surfaceChanged format:4, width:1920, height:1079 05-01 18:32:12.169 I/G2TvFragment( 8541): onHiddenChanged() called. hidden : false 05-01 18:32:12.170 D/G2TvFragment( 8541): onFocusUnBlock() 05-01 18:32:12.170 D/G2TvFragment( 8541): onRecall 05-01 18:32:12.170 D/MainActivity( 8541): onGlobalFocusChanged() called. old : android.widget.RelativeLayout{4d7f67f VFE...... ......ID 0,0-1920,1080 #7f0804a5 app:id/id_a_m ain_layout} 05-01 18:32:12.171 E/Matrix.Framework(19500): [29.922518 18:32:12.171665 0.004255] at /system/lib/libtvs_mediaserver_hal.so.0xae2594b5: AML_AVPLAY_Destroy+0x4c 05-01 18:32:12.172 E/Matrix.Framework(19500): [29.923089 18:32:12.172236 0.000571] at /system/lib/libtvs_mediaserver_hal.so.0xae253a1d: _ZN7tvstorm12AmlHalPlayer5closeEv+0 x10c 05-01 18:32:12.172 E/Matrix.Framework(19500): [29.923444 18:32:12.172591 0.000355] at /system/lib/libtvs_mediaserver.so.0xadd8ac63: _ZN7tvstorm9HalPlayer5closeEv+0x52 05-01 18:32:12.173 E/Matrix.Framework(19500): [29.924228 18:32:12.173375 0.000318] at /system/lib/libtvs_utils.so.0xacca8ee5: _ZN7tvstorm12SerialRunner3runEPv+0xb8 05-01 18:32:12.173 E/Matrix.Framework(19500): [29.924794 18:32:12.173941 0.000566] at /system/lib/libtvs_utils.so.0xacca5201: (null)+0xacca5201 05-01 18:32:12.174 E/Matrix.Framework(19500): [29.925240 18:32:12.174387 0.000446] at /system/lib/libc.so.0xacf33c47: (null)+0xacf33c47 05-01 18:32:12.174 E/Matrix.Framework(19500): [29.925710 18:32:12.174857 0.000470] at /system/lib/libc.so.0xaceee067: (null)+0xaceee067 05-01 18:32:12.176 D/MainActivity( 8541): onGlobalFocusChanged() called. new : tvs.html5.core.internal.TVSHTML5ContentView$TVSHTML5ContentViewApi23{8bdb3aa VFE...C.. .F....I D 0,0-1920,1080} 05-01 18:32:12.176 D/MainActivity( 8541): onGlobalFocusChanged() called. old : tvs.html5.core.TVSHTML5View{248d64c VFE...... .F....ID 0,0-1920,1080 #7f080719 app:id/popup_ne xt_tv_browser_webview} 05-01 18:32:12.176 D/MainActivity( 8541): onGlobalFocusChanged() called. new : tvs.html5.core.TVSHTML5View{248d64c VFE...... .F....ID 0,0-1920,1080 #7f080719 app:id/popup_ne xt_tv_browser_webview} 05-01 18:32:12.176 D/G2TvFragment( 8541): onRecall mLoadingFinishFlag: true, mReloadFlag : false 05-01 18:32:12.176 D/G2TvFragment( 8541): onRecall processCommandBundle mPause : false 05-01 18:32:12.176 D/G2TvFragment( 8541): processCommandBundle 05-01 18:32:12.176 D/G2TvFragment( 8541): processCommandBundle COMMAND_CODE_NAVIGATE_WEB_MENU mSendStbInfo : true 05-01 18:32:12.176 D/G2TvFragment( 8541): sendJsonData 05-01 18:32:12.176 D/G2TvFragment( 8541): sendJsonData script core.receiveMessageFromNative({"TYPE":"request","COMMAND":"MenuNavigationWeb","CONTENTS":"","DATA":{"menuType": "synopsis","extInfo":{"trackId":"","blockTrackId":"","uxReference":"","sessionId":"","search_type":"2","sris_id":"","epsd_id":"","epsd_rslu_id":"{AEC9362D-3C61-4F98-95B4-1C0 8FC43BF21}","seeingPath":"99","title":"","isKidsContents":"N","adultCheck":false,"fromCommerce":false,"showGnbNavigation":"N","currentPlayState":"6"}}}); 05-01 18:32:12.177 D/G2TvFragment( 8541): processCommandBundle processed : true 05-01 18:32:12.177 D/G2TvFragment( 8541): onRecall mPause : false 05-01 18:32:12.177 D/SendInterfaceManager( 8541): SendInterfaceManager getInstance 05-01 18:32:12.177 D/SendInterfaceManager( 8541): sendWebShowNotify 05-01 18:32:12.177 D/SendCommon( 8541): getSendJsonData 05-01 18:32:12.177 D/SendCommon( 8541): getSendJsonData mCommonObj : {"TYPE":"notify","COMMAND":"WebShowNoti","CONTENTS":""} 05-01 18:32:12.177 I/UIControlManager( 8541): processBundle() called. bundleType : COMMAND 05-01 18:32:12.177 D/UIControlManager( 8541): processBundle() commandBundleType : SEND_JSON_DATA 05-01 18:32:12.177 D/UIControlManager( 8541): processBundle() currentFragment : G2TvFragment{25f6f43 #1 id=0x7f08049e} 05-01 18:32:12.177 D/G2TvFragment( 8541): onReceiveBundle 05-01 18:32:12.177 D/G2TvFragment( 8541): processCommandBundle 05-01 18:32:12.177 D/G2TvFragment( 8541): processCommandBundle COMMAND_CODE_SEND_JAVASCRIPT 05-01 18:32:12.177 D/G2TvFragment( 8541): sendJsonData 05-01 18:32:12.177 D/G2TvFragment( 8541): sendJsonData script core.receiveMessageFromNative({"TYPE":"notify","COMMAND":"WebShowNoti","CONTENTS":""}); 05-01 18:32:12.177 D/G2TvFragment( 8541): processCommandBundle processed : true 05-01 18:32:12.177 I/G2TvFragment( 8541): onRecall() called by SingletonFragment 05-01 18:32:12.177 I/ToastUtil( 8541): showThreadToast() called. descMain : 일시적인 장애로 서비스가 원활하지 않습니다. 05-01 18:32:12.177 I/ToastUtil( 8541): 잠시 후 다시 이용해주세요. R201, descSub : null 05-01 18:32:12.179 I/ToastUtil( 8541): refreshDrawable() called 05-01 18:32:12.190 D/MainActivity( 8541): onGlobalFocusChanged() called. old : tvs.html5.core.TVSHTML5View{248d64c VFE...... ......ID 0,0-1920,1080 #7f080719 app:id/popup_ne xt_tv_browser_webview} 05-01 18:32:12.190 D/MainActivity( 8541): onGlobalFocusChanged() called. new : tvs.html5.core.internal.TVSHTML5ContentView$TVSHTML5ContentViewApi23{8bdb3aa VFE...C.. .F....I D 0,0-1920,1080} 05-01 18:32:12.202 D/mali_winsys( 8541): EGLint new_window_surface(egl_winsys_display , void , EGLSurface, EGLConfig, egl_winsys_surface , EGLBoolean) returns 0x3000 05-01 18:32:12.203 I/[Gralloc]( 3007): framebuffer hal alread move to hwcomposer 05-01 18:32:12.206 I/TVStormIME( 3657): onFinishInput() -- START 05-01 18:32:12.206 I/KeyboardIndicator( 3657): dismiss() 05-01 18:32:12.206 I/TVStormIME( 3657): onFinishInput() -- E N D 05-01 18:32:12.206 I/TVStormIME( 3657): onStartInput(), restarting : false -- START 05-01 18:32:12.206 D/TVStormIME( 3657): onStartInput), null, com.skb.tv 05-01 18:32:12.206 I/TVStormIME( 3657): onStartInput(), restarting : false -- E N D 05-01 18:32:12.208 I/ISQMSManager( 8541): agent_send_event() called. event_id : E14, status : open 05-01 18:32:12.208 D/TVSystemAdapterBase( 4020): version = 15.502.15 05-01 18:32:12.209 D/STBAPIManager( 8541): getProperty() key : STBID, result : {5F8011C8-38B7-11E9-B4BF-A911074387AC} 05-01 18:32:12.209 D/STBAPIManager( 8541): getSTBId() Read stb id from Extension API 05-01 18:32:12.211 I/AGENT ( 4794): *send_event eventid=E14, status=open 05-01 18:32:12.212 I/ISQMSManager( 8541): agent_send_data() called 05-01 18:32:12.212 D/ISQMSManager( 8541): agent_send_data() category_id : 0, sub_category_id : -1, field_id : 7, data : 190501-20 05-01 18:32:12.214 I/AGENT ( 4794): send_data=190501-20 05-01 18:32:12.214 D/STBAPIManager( 8541): getProperty() key : GW_TOKEN, result : c56fb288-36e3-44c6-876d-ea25c3abc960 05-01 18:32:12.214 D/STBAPIManager( 8541): getToken : c56fb288-36e3-44c6-876d-ea25c3abc960 05-01 18:32:12.215 D/STBAPIManager( 8541): getProperty() key : STBID, result : {5F8011C8-38B7-11E9-B4BF-A911074387AC} 05-01 18:32:12.215 I/ISQMSManager( 8541): agent_send_data() called 05-01 18:32:12.215 D/STBAPIManager( 8541): getSTBId() Read stb id from Extension API 05-01 18:32:12.215 D/ISQMSManager( 8541): agent_send_data() category_id : 0, sub_category_id : -1, field_id : 11, data : HOM 05-01 18:32:12.215 I/AGENT ( 4794): send_data=HOM 05-01 18:32:12.215 I/[Gralloc]( 3007): framebuffer hal alread move to hwcomposer 05-01 18:32:12.216 I/ISQMSDataBuilder( 8541): getDataCheckSVC() called 05-01 18:32:12.216 I/ISQMSManager( 8541): agent_send_data() called 05-01 18:32:12.216 D/ISQMSManager( 8541): agent_send_data() category_id : 2, sub_category_id : 3, field_id : -1, data : ;{AEC9362D-3C61-4F98-95B4-1C08FC43BF21}; 05-01 18:32:12.217 I/AGENT ( 4794): send_data=;{AEC9362D-3C61-4F98-95B4-1C08FC43BF21}; 05-01 18:32:12.217 D/EthernetAdapterBase( 4020): 0.getEthernetState state: ETHERNET_STATE_ENABLED 05-01 18:32:12.217 D/EthernetAdapterBase( 4020): 1.getEthernetState interface: eth0 05-01 18:32:12.217 D/EthernetAdapterBase( 4020): IpAssignment is (UNASSIGNED) setIpAssignment to (DHCP) 05-01 18:32:12.218 D/EthernetAdapterBase( 4020): 2.getEthernetState DHCP 05-01 18:32:12.218 D/EthernetAdapterBase( 4020): 3.getEthernetState ip: 110.12.45.204 05-01 18:32:12.218 D/EthernetAdapterBase( 4020): 3.getEthernetState gateway: 110.12.45.193 05-01 18:32:12.218 D/EthernetAdapterBase( 4020): 3.getEthernetState subnet: 255.255.255.192 05-01 18:32:12.218 D/EthernetAdapterBase( 4020): 3.getEthernetState dns1 : 210.220.163.82 05-01 18:32:12.218 D/EthernetAdapterBase( 4020): 3.getEthernetState dns2 : 219.250.36.130 05-01 18:32:12.218 I/ISQMSDataBuilder( 8541): getDataCheckVOD1() called 05-01 18:32:12.218 I/ISQMSManager( 8541): agent_send_data() called 05-01 18:32:12.218 D/ISQMSManager( 8541): agent_send_data() category_id : 2, sub_category_id : 4, field_id : -1, data : ;agw.sk-iptv.com;;0.0.0.0;00.044 05-01 18:32:12.218 I/AGENT ( 4794): send_data=;agw.sk-iptv.com;;0.0.0.0;00.044 05-01 18:32:12.220 D/TVSystemAdapterBase( 4020): version = 15.502.15 05-01 18:32:12.221 D/Sha256Manager( 8541): Sha256Manage encoding called and Param: c56fb288-36e3-44c6-876d-ea25c3abc96020190501183212.214 05-01 18:32:12.221 D/STBAPIManager( 8541): getProperty() key : GW_TOKEN, result : c56fb288-36e3-44c6-876d-ea25c3abc960 05-01 18:32:12.221 D/STBAPIManager( 8541): getToken : c56fb288-36e3-44c6-876d-ea25c3abc960 05-01 18:32:12.222 D/STBAPIManager( 8541): getKidsZoneEntry called 05-01 18:32:12.222 D/STBAPIManager( 8541): getProperty() key : KIDS_MODE_ENTRY, result : 0 05-01 18:32:12.222 D/KidszoneManager( 8541): Kidszone is false 05-01 18:32:12.223 I/ISQMSDataBuilder( 8541): getDataCheckVOD3() called 05-01 18:32:12.223 I/ISQMSManager( 8541): agent_send_data() called 05-01 18:32:12.223 D/ISQMSManager( 8541): agent_send_data() category_id : 2, sub_category_id : 6, field_id : -1, data : ;티라노 사우르스 렉스[];skbvod://cdn2.hanafostv.com:5 54/VOD/51/CS11010351/CE1000163529_0_TVHD_201903120315.ts.pac?ci={AEC9362D-3C61-4F98-95B4-1C08FC43BF21}&oi=747aCE1000163529_0_TVHD_2019&op=4a0d1999030&rp=51 05-01 18:32:12.223 I/AGENT ( 4794): send_data=;티라노 사우르스 렉스[];skbvod://cdn2.hanafostv.com:554/VOD/51/CS11010351/CE1000163529_0_TVHD_201903120315.ts.pac?ci={AEC9362 D-3C61-4F98-95B4-1C08FC43BF21}&oi=747aCE1000163529_0_TVHD_2019&op=4a0d1999030&rp=51 05-01 18:32:12.223 I/[Gralloc]( 3007): framebuffer hal alread move to hwcomposer 05-01 18:32:12.224 I/ISQMSDataBuilder( 8541): getDataCheckVOD4() called 05-01 18:32:12.224 I/ISQMSManager( 8541): agent_send_data() called 05-01 18:32:12.224 D/ISQMSManager( 8541): agent_send_data() category_id : 2, sub_category_id : 7, field_id : -1, data : ;0;R201 05-01 18:32:12.224 D/STBAPIManager( 8541): getProperty() key : PROPERTY_GIFT_MODE, result : 0 05-01 18:32:12.224 I/STBGlobal( 8541): getGiftMode : 0 05-01 18:32:12.225 I/AGENT ( 4794): send_data=;0;R201 05-01 18:32:12.225 I/ISQMSManager( 8541): agent_send_event() called. event_id : E14, status : close 05-01 18:32:12.226 I/AGENT ( 4794): *send_event eventid=E14, status=close 05-01 18:32:12.226 I/G2JavascriptInterface( 8541): sendMessage called direction: AppToContainer, jsonString : {"TYPE":"response","COMMAND":"MenuNavigationWeb","CONTENTS":"", "DATA":{"menuType":"synopsis","result":"success"}} 05-01 18:32:12.226 I/G2JavascriptInterface( 8541): dataParser command : MENU_NAVIGATION_WEB 05-01 18:32:12.228 D/EthernetAdapterBase( 4020): 0.getEthernetState state: ETHERNET_STATE_ENABLED 05-01 18:32:12.228 D/EthernetAdapterBase( 4020): 1.getEthernetState interface: eth0 05-01 18:32:12.231 D/HomeAdManager( 8541): getHomeAdInfo.handleMessage() called 05-01 18:32:12.231 D/HomeAdManager( 8541): getHomeAdInfo() mLastStatus.m_oErrorInteger : 0 05-01 18:32:12.231 D/HomeAdManager( 8541): getHomeAdInfo() MetaVersion : 201905010600020000007376 05-01 18:32:12.232 D/HomeAdManager( 8541): getHomeAdInfo() homeAdInfo.m_oHintList size : 31 05-01 18:32:12.232 I/ISQMSManager( 8541): mAgentSendHandler.handleMessage() called. MESSAGE_RESPONSE_AGENT_OK 05-01 18:32:12.232 D/ReceiveInterfaceManager( 8541): ReceiveInterfaceManager getInstance 05-01 18:32:12.232 D/ReceiveInterfaceManager( 8541): processMenuNavigationWeb 05-01 18:32:12.232 D/ReceiveCommon( 8541): isUnsuitable str : response, compareStr : response 05-01 18:32:12.232 D/ReceiveCommon( 8541): isUnsuitable str : MenuNavigationWeb, compareStr : MenuNavigationWeb 05-01 18:32:12.232 D/ReceiveCommon( 8541): isUnsuitable str : , compareStr : 05-01 18:32:12.232 D/PushManager( 8541): ShowPopupMsg : null 05-01 18:32:12.233 D/ReceiveInterfaceManager( 8541): processMenuNavigationWeb result : success 05-01 18:32:12.235 D/EthernetAdapterBase( 4020): IpAssignment is (UNASSIGNED) setIpAssignment to (DHCP) 05-01 18:32:12.235 D/EthernetAdapterBase( 4020): 2.getEthernetState DHCP 05-01 18:32:12.235 D/EthernetAdapterBase( 4020): 3.getEthernetState ip: 110.12.45.204 05-01 18:32:12.235 D/EthernetAdapterBase( 4020): 3.getEthernetState gateway: 110.12.45.193 05-01 18:32:12.235 D/EthernetAdapterBase( 4020): 3.getEthernetState subnet: 255.255.255.192 05-01 18:32:12.235 D/EthernetAdapterBase( 4020): 3.getEthernetState dns1 : 210.220.163.82 05-01 18:32:12.235 D/EthernetAdapterBase( 4020): 3.getEthernetState dns2 : 219.250.36.130 05-01 18:32:12.236 I/server-module( 8541): URL : https://lgs.hanafostv.com:8443/LGS/v5/cdrLog.jsp 05-01 18:32:12.236 D/STBAPIManager( 8541): getProperty() key : PROPERTY_SERVER_LOG_SHOW_OR_NOT, result : 05-01 18:32:12.244 I/crash_dump32(19776): obtaining output fd from tombstoned, type: kDebuggerdTombstone 05-01 18:32:12.244 I//system/bin/tombstoned( 3120): received crash request for pid 19757 05-01 18:32:12.245 I/crash_dump32(19776): performing dump of process 19500 (target tid = 19757) 05-01 18:32:12.246 I/G2JavascriptInterface( 8541): sendMessage called direction: AppToContainer, jsonString : {"TYPE":"notify","COMMAND":"SendNuguLog","CONTENTS":"","DATA":{ "menu":"SYNOPSIS_NORMAL","status":"in"}} 05-01 18:32:12.246 D/ReceiveInterfaceManager( 8541): ReceiveInterfaceManager getInstance 05-01 18:32:12.246 D/ReceiveInterfaceManager( 8541): processSendNuguLog 05-01 18:32:12.246 D/ReceiveCommon( 8541): isUnsuitable str : notify, compareStr : notify 05-01 18:32:12.246 D/ReceiveCommon( 8541): isUnsuitable str : SendNuguLog, compareStr : SendNuguLog 05-01 18:32:12.246 D/ReceiveCommon( 8541): isUnsuitable str : , compareStr : 05-01 18:32:12.246 D/ReceiveInterfaceManager( 8541): processSendNuguLog menu : SYNOPSIS_NORMAL 05-01 18:32:12.246 D/ReceiveInterfaceManager( 8541): processSendNuguLog status : in 05-01 18:32:12.246 D/ContextualData( 8541): getWebState \| IN \| start 05-01 18:32:12.246 D/Contextual( 8541): makeUri \| {"from":"com.skb.tv","domain":"synopsis","extras":{"state":"start","type":"single"}} 05-01 18:32:12.246 W/ContextImpl( 8541): Calling a method in the system process without a qualified user: android.app.ContextImpl.sendBroadcast:1005 android.content.ContextW rapper.sendBroadcast:444 com.skb.google.tv.global.contextual.Contextual.sendBroadcast:84 com.skb.google.tv.global.contextual.Contextual.sendStatus:27 com.skb.google.tv.conne ctif.ReceiveInterfaceManager.processSendNuguLog:1713 05-01 18:32:12.248 E/ActivityManager( 3253): java.lang.Throwable 05-01 18:32:12.248 E/ActivityManager( 3253): at com.android.server.am.ActivityManagerService.checkBroadcastFromSystem(ActivityManagerService.java:21228) 05-01 18:32:12.248 E/ActivityManager( 3253): at com.android.server.am.ActivityManagerService.broadcastIntentLocked(ActivityManagerService.java:21832) 05-01 18:32:12.248 E/ActivityManager( 3253): at com.android.server.am.ActivityManagerService.broadcastIntent(ActivityManagerService.java:21974) 05-01 18:32:12.248 E/ActivityManager( 3253): at android.app.IActivityManager$Stub.onTransact$broadcastIntent$(IActivityManager.java:10171) 05-01 18:32:12.248 E/ActivityManager( 3253): at android.app.IActivityManager$Stub.onTransact(IActivityManager.java:167) 05-01 18:32:12.248 E/ActivityManager( 3253): at com.android.server.am.ActivityManagerService.onTransact(ActivityManagerService.java:3299) 05-01 18:32:12.248 E/ActivityManager( 3253): at android.os.Binder.execTransact(Binder.java:731) 05-01 18:32:12.249 I/netclient( 8541): host address = '221.143.164.41' 05-01 18:32:12.249 I/netclient( 8541): [NET]Url open : 12 05-01 18:32:12.260 F/DEBUG (19776): * * * * * * * * * * * * * * * *** 05-01 18:32:12.260 F/DEBUG (19776): Build fingerprint: 'SKB/BFX_AT100/BFX-AT100:9/PI/20190429:userdebug/release-keys' 05-01 18:32:12.260 F/DEBUG (19776): Revision: '0' 05-01 18:32:12.260 F/DEBUG (19776): ABI: 'arm' 05-01 18:32:12.261 F/DEBUG (19776): signal 11 (SIGSEGV), code 1 (SEGV_MAPERR), fault addr 0x5 05-01 18:32:12.261 F/DEBUG (19776): Cause: null pointer dereference 05-01 18:32:12.261 F/DEBUG (19776): r0 00000001 r1 a8429828 r2 00000001 r3 00000000 05-01 18:32:12.261 F/DEBUG (19776): r4 ae26b02c r5 a8cee000 r6 a8ca75a0 r7 00000000 05-01 18:32:12.261 F/DEBUG (19776): r8 a8cbb430 r9 51eb851f r10 00000001 r11 a8cbb3d8 05-01 18:32:12.261 F/DEBUG (19776): ip ae26ab40 sp a8429820 lr ae2594b5 pc a9dec854 05-01 18:32:12.273 I/netclient( 8541): [NET]Set option : 36 05-01 18:32:12.273 I/netclient( 8541): [NET]Connect : 37 05-01 18:32:12.277 F/DEBUG (19776): 05-01 18:32:12.277 F/DEBUG (19776): backtrace: 05-01 18:32:12.278 F/DEBUG (19776): #00 pc 00009854 /system/lib/libutils.so (android::RefBase::incStrong(void const) const) 05-01 18:32:12.278 F/DEBUG (19776): #01 pc 000164b1 /system/lib/libtvs_mediaserver_hal.so (AML_AVPLAY_Destroy+72) 05-01 18:32:12.278 F/DEBUG (19776): #02 pc 00010a19 /system/lib/libtvs_mediaserver_hal.so (tvstorm::AmlHalPlayer::close()+264) 05-01 18:32:12.278 F/DEBUG (19776): #03 pc 0003ec61 /system/lib/libtvs_mediaserver.so (tvstorm::HalPlayer::close()+80) 05-01 18:32:12.278 F/DEBUG (19776): #05 pc 00026ee3 /system/lib/libtvs_utils.so (tvstorm::SerialRunner::run(void)+182) 05-01 18:32:12.278 F/DEBUG (19776): #06 pc 000231ff /system/lib/libtvs_utils.so (_ZNSt3__114__thread_proxyINS_5tupleIJNS_10unique_ptrINS_15__thread_structENS_14defaul t_deleteIS3_EEEEZN7tvstorm6Thread5startEvE3$_0EEEEEPvSB_+30) 05-01 18:32:12.278 F/DEBUG (19776): #07 pc 00063c45 /system/lib/libc.so (__pthread_start(void)+22) 05-01 18:32:12.278 F/DEBUG (19776): #08 pc 0001e065 /system/lib/libc.so (__start_thread+22) 05-01 18:32:12.286 D/NetClientCore( 8541): testResponseSuccess iStatusCode: 200 05-01 18:32:12.287 I/netclient( 8541): [NET]Get header : 50 05-01 18:32:12.287 I/netclient( 8541): [NET]Get body : 50 05-01 18:32:12.289 I/netclient( 8541): [NET]Final : 52 05-01 18:32:12.291 D/STBAPIManager( 8541): getProperty() key : PROPERTY_SERVER_LOG_SHOW_OR_NOT, result : 05-01 18:32:12.291 I/VODPlayer( 8541): License reset is false 05-01 18:32:12.291 I/CDR ( 8541): Send VOD Log recv MESSAGE_RESPONSE_SEND_CDR_LOG_OK 05-01 18:32:12.299 I/G2JavascriptInterface( 8541): sendMessage called direction: AppToContainer, jsonString : {"TYPE":"response","COMMAND":"WebShowNoti","CONTENTS":"","DATA" :""} 05-01 18:32:12.299 I/G2JavascriptInterface( 8541): dataParser command : WEB_SHOW_NOTI 05-01 18:32:12.300 D/ReceiveInterfaceManager( 8541): ReceiveInterfaceManager getInstance 05-01 18:32:12.300 D/ReceiveInterfaceManager( 8541): processWebShowNoti 05-01 18:32:12.300 I/UIControlManager( 8541): processBundle() called. bundleType : COMMAND 05-01 18:32:12.300 D/UIControlManager( 8541): processBundle() commandBundleType : SEND_JSON_DATA 05-01 18:32:12.300 D/UIControlManager( 8541): processBundle() currentFragment : G2TvFragment{25f6f43 #1 id=0x7f08049e} 05-01 18:32:12.300 D/G2TvFragment( 8541): onReceiveBundle 05-01 18:32:12.300 D/G2TvFragment( 8541): processCommandBundle 05-01 18:32:12.300 D/G2TvFragment( 8541): processCommandBundle processed : false 05-01 18:32:12.326 I/ISQMSManager( 8541): agent_send_event() called. event_id : H09, status : open 05-01 18:32:12.327 I/AGENT ( 4794): **send_event eventid=H09, status=open 05-01 18:32:12.327 I/ISQMSManager( 8541): agent_send_data() called 05-01 18:32:12.327 D/ISQMSManager( 8541): agent_send_data() category_id : 0, sub_category_id : -1, field_id : 7, data : 190501-20 05-01 18:32:12.327 I/AGENT ( 4794): send_data=190501-20 05-01 18:32:12.327 I/ISQMSManager( 8541): agent_send_data() called 05-01 18:32:12.327 D/ISQMSManager( 8541): agent_send_data() category_id : 0, sub_category_id : -1, field_id : 11, data : HOM 05-01 18:32:12.327 I/AGENT ( 4794): send_data=HOM 05-01 18:32:12.328 I/ISQMSManager( 8541): agent_send_event() called. event_id : H09, status : close 05-01 18:32:12.328 I/AGENT ( 4794): **send_event eventid=H09, status=close 05-01 18:32:12.328 I/ISQMSManager( 8541): mAgentSendHandler.handleMessage() called. MESSAGE_RESPONSE_AGENT_OK 05-01 18:32:12.521 W/NativeCrashListener( 3253): Couldn't find ProcessRecord for pid 19500 05-01 18:32:12.522 E//system/bin/tombstoned( 3120): Tombstone written to: /data/tombstones/tombstone_18 05-01 18:32:12.532 I/BootReceiver( 3253): Copying /data/tombstones/tombstone_18 to DropBox (SYSTEM_TOMBSTONE) 05-01 18:32:12.551 E/HidlPlayerProxy( 3097): Failed to call hidl ~HidlPlayerProxy() 05-01 18:32:12.552 I/BtvMediaPlayer( 8541): [resetMediaPlayer] after MediaPlayer.release() 05-01 18:32:12.552 I/BtvMediaPlayer( 8541): setState PlaybackState: End 05-01 18:32:12.552 I/BtvMediaPlayer( 8541): [resetMediaPlayer] before new MediaPlayer() 05-01 18:32:12.555 I/BtvMediaPlayer( 8541): [resetMediaPlayer] after new MediaPlayer() 05-01 18:32:12.555 I/BtvMediaPlayer( 8541): [resetMediaPlayer] before MediaPlayer.setOnPreparedListener() 05-01 18:32:12.555 I/BtvMediaPlayer( 8541): [resetMediaPlayer] after MediaPlayer.setOnPreparedListener() 05-01 18:32:12.555 I/BtvMediaPlayer( 8541): [resetMediaPlayer] before MediaPlayer.setOnInfoListener() 05-01 18:32:12.555 I/BtvMediaPlayer( 8541): [resetMediaPlayer] after MediaPlayer.setOnInfoListener() 05-01 18:32:12.555 I/BtvMediaPlayer( 8541): [resetMediaPlayer] before MediaPlayer.setOnErrorListener() 05-01 18:32:12.555 I/BtvMediaPlayer( 8541): [resetMediaPlayer] after MediaPlayer.setOnErrorListener() 05-01 18:32:12.555 I/BtvMediaPlayer( 8541): [resetMediaPlayer] before MediaPlayer.setOnCompletionListener() 05-01 18:32:12.555 I/BtvMediaPlayer( 8541): [resetMediaPlayer] after MediaPlayer.setOnCompletionListener() 05-01 18:32:12.555 I/BtvMediaPlayer( 8541): [resetMediaPlayer] before MediaPlayer.setScreenOnWhilePlaying() 05-01 18:32:12.555 W/MediaPlayer( 8541): setScreenOnWhilePlaying(true) is ineffective without a SurfaceHolder 05-01 18:32:12.555 I/BtvMediaPlayer( 8541): [resetMediaPlayer] after MediaPlayer.setScreenOnWhilePlaying() 05-01 18:32:12.555 I/BtvMediaPlayer( 8541): [resetMediaPlayer] before MediaPlayer.setAudioAttributes() 05-01 18:32:12.557 I/BtvMediaPlayer( 8541): [resetMediaPlayer] after MediaPlayer.setAudioAttributes() 05-01 18:32:12.557 I/BtvMediaPlayer( 8541): setState PlaybackState: Idle 05-01 18:32:12.557 I/BtvMediaPlayer( 8541): CC Surface hideCCSurface() 05-01 18:32:12.557 E/MediaPlayerNative( 8541): invoke failed: wrong state 1, mPlayer(0x0) 05-01 18:32:12.557 I/BtvMediaPlayer( 8541): CC Surface hideCCSurface() invoke Exception 05-01 18:32:12.557 I/BtvMediaPlayer( 8541): [stopMedia] before MediaPlayer.reset() 05-01 18:32:12.557 E/HidlMediaServerProxy( 4020): tvs_mediaserver died, reconnect 05-01 18:32:12.557 E/HidlMediaServerProxy( 4081): tvs_mediaserver died, reconnect 05-01 18:32:12.557 E/HidlMediaServerProxy( 3097): tvs_mediaserver died, reconnect 05-01 18:32:12.558 I/TVSMMFClient( 4020): ===== tvs_mediaserver event callback: 0xa35b6e3c 05-01 18:32:12.558 I/TVSMMFClient( 4081): ===== tvs_mediaserver event callback: 0xad40a52c 05-01 18:32:12.558 W/DataCasting( 4081): JNI_DatacastingImpl::tvsMediaCallback - data is null 05-01 18:32:12.558 I/JNI_Settings( 4020): tvsMediaCallback(10000) 05-01 18:32:12.558 D/BtvMediaPlayer( 8541): cc surfaceDestroyed 05-01 18:32:12.558 I/JNI_Settings( 4020): what(10000), extra(0), data size(0) 05-01 18:32:12.562 V/MediaPlayer( 8541): resetDrmState: mDrmInfo=null mDrmProvisioningThread=null mPrepareDrmInProgress=false mActiveDrmScheme=false 05-01 18:32:12.562 V/MediaPlayer( 8541): cleanDrmObj: mDrmObj=null mDrmSessionId=null 05-01 18:32:12.562 I/BtvMediaPlayer( 8541): [stopMedia] after MediaPlayer.reset() 05-01 18:32:12.562 I/BtvMediaPlayer( 8541): CC Surface hideCCSurface() 05-01 18:32:12.562 I/ServiceManagement( 3097): getService: Trying again for tvstorm.mmf@1.0::IHidlMediaServer/default... 05-01 18:32:12.562 E/MediaPlayerNative( 8541): invoke failed: wrong state 1, mPlayer(0x0) 05-01 18:32:12.562 I/BtvMediaPlayer( 8541): CC Surface hideCCSurface() invoke Exception 05-01 18:32:12.562 I/BtvMediaPlayer( 8541): [stopMedia] before MediaPlayer.reset() 05-01 18:32:12.563 V/MediaPlayer( 8541): resetDrmState: mDrmInfo=null mDrmProvisioningThread=null mPrepareDrmInProgress=false mActiveDrmScheme=false 05-01 18:32:12.563 V/MediaPlayer( 8541): cleanDrmObj: mDrmObj=null mDrmSessionId=null 05-01 18:32:12.563 I/BtvMediaPlayer( 8541): [stopMedia] after MediaPlayer.reset() 05-01 18:32:12.563 I/BtvMediaPlayer( 8541): CC Surface hideCCSurface() 05-01 18:32:12.563 E/MediaPlayerNative( 8541): invoke failed: wrong state 1, mPlayer(0x0) 05-01 18:32:12.563 I/BtvMediaPlayer( 8541): CC Surface hideCCSurface() invoke Exception 05-01 18:32:12.563 I/BtvMediaPlayer( 8541): [playMedia] before MediaPlayer.setDisplay() 05-01 18:32:12.563 I/BtvMediaPlayer( 8541): [playMedia] after MediaPlayer.setDisplay() 05-01 18:32:12.563 I/BtvMediaPlayer( 8541): [playMedia] before MediaPlayer.reset() 05-01 18:32:12.563 V/MediaPlayer( 8541): resetDrmState: mDrmInfo=null mDrmProvisioningThread=null mPrepareDrmInProgress=false mActiveDrmScheme=false 05-01 18:32:12.563 V/MediaPlayer( 8541): cleanDrmObj: mDrmObj=null mDrmSessionId=null 05-01 18:32:12.563 I/BtvMediaPlayer( 8541): [playMedia] after MediaPlayer.reset() 05-01 18:32:12.565 I/ServiceManagement( 4081): getService: Trying again for tvstorm.mmf@1.0::IHidlMediaServer/default... 05-01 18:32:12.573 I/ServiceManagement( 4020): getService: Trying again for tvstorm.mmf@1.0::IHidlMediaServer/default... 05-01 18:32:12.580 W/BroadcastQueue( 3253): Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com .google.android.gms/.stats.service.DropBoxEntryAddedReceiver 05-01 18:32:12.580 W/BroadcastQueue( 3253): Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com .google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver 05-01 18:32:12.662 V/SettingsProvider( 3253): Notifying for 0: content://settings/system/volume_music_hdmi 05-01 18:32:12.663 I/BtvMediaPlayer( 8541): [playMedia] before MediaPlayer.setDataSource() 05-01 18:32:12.664 I/TVSGtvPlayerFactory( 3097): scoreFactory(): url=skbfile:///data/skb_data/DATA/adv/cache/O0000001033_190425190526.ts, curScore=0.000000 05-01 18:32:12.667 I/ServiceManagement( 3097): getService: Trying again for tvstorm.mmf@1.0::IHidlMediaServer/default... 05-01 18:32:13.492 I/AVUtils (19784): createInstance(32bit) : _ZN7android19createExtendedUtilsEv 05-01 18:32:13.499 I/AVUtils (19784): createInstance(32bit) : _ZN7android19createExtendedUtilsEv 05-01 18:32:13.499 I/AVUtils (19784): Opened libavenhancements.so 05-01 18:32:13.499 I/AVUtils (19784): symbol _ZN7android19createExtendedUtilsEv found: (null) 05-01 18:32:13.499 I/AVUtils (19784): CREATE function success 05-01 18:32:13.499 I/AVUtils (19784): Opened libavenhancements.so 05-01 18:32:13.499 I/AVUtils (19784): symbol _ZN7android19createExtendedUtilsEv found: (null) 05-01 18:32:13.499 I/AVUtils (19784): CREATE function success 05-01 18:32:13.499 I/AVUtils (19784): createInstance(32bit) : _ZN7android19createExtendedUtilsEv 05-01 18:32:13.499 I/AVUtils (19784): symbol _ZN7android19createExtendedUtilsEv found: (null) 05-01 18:32:13.499 I/AVUtils (19784): CREATE function success 05-01 18:32:13.500 I/CasContext(19784): TSID(0), used(FALSE), URL(), DESCRAMBLE_STATUS(STOP), EMMFILTERING_STATUS(STOP) 05-01 18:32:13.500 I/CasContext(19784): TSID(1), used(FALSE), URL(), DESCRAMBLE_STATUS(STOP), EMMFILTERING_STATUS(STOP) 05-01 18:32:13.500 I/CasContext(19784): TSID(2), used(FALSE), URL(), DESCRAMBLE_STATUS(STOP), EMMFILTERING_STATUS(STOP) 05-01 18:32:13.500 I/CasContext(19784): TSID(3), used(FALSE), URL(), DESCRAMBLE_STATUS(STOP), EMMFILTERING_STATUS(STOP) 05-01 18:32:13.500 I/CasContext(19784): TSID(4), used(FALSE), URL(), DESCRAMBLE_STATUS(STOP), EMMFILTERING_STATUS(STOP) 05-01 18:32:13.500 I/CasContext(19784): TSID(5), used(FALSE), URL(), DESCRAMBLE_STATUS(STOP), EMMFILTERING_STATUS(STOP) 05-01 18:32:13.500 I/CasContext(19784): TSID(6), used(FALSE), URL(), DESCRAMBLE_STATUS(STOP), EMMFILTERING_STATUS(STOP) 05-01 18:32:13.500 I/CasContext(19784): TSID(7), used(FALSE), URL(), DESCRAMBLE_STATUS(STOP), EMMFILTERING_STATUS(STOP) 05-01 18:32:13.500 I/CasContext(19784): TSID(8), used(FALSE), URL(), DESCRAMBLE_STATUS(STOP), EMMFILTERING_STATUS(STOP) 05-01 18:32:13.500 D/tvs_mediaserver(19784): main() >> begin 05-01 18:32:13.500 I/tvs_mediaserver(19784): Init tvs_mediaserver. 05-01 18:32:13.500 D/TVSMediaServerImpl(19784): init() >> begin 05-01 18:32:13.500 D/MediaGateway(19784): MediaGateway() >> begin 05-01 18:32:13.500 D/MediaGateway(19784): MediaGateway() << end 0 ms 05-01 18:32:13.500 D/MediaGateway(19784): onInit() >> begin 05-01 18:32:13.500 I/MediaGateway(19784): onInit():65 NOT IMPLEMENTED 05-01 18:32:13.500 D/MediaGateway(19784): onInit() << end 0 ms 05-01 18:32:13.500 D/AmlHalFactory(19784): init() >> begin 05-01 18:32:13.500 D/AmlHalFactory(19784): init() << end 0 ms 05-01 18:32:13.500 D/TunerManager(19784): init() >> begin 05-01 18:32:13.500 D/TunerManager(19784): init() << end 0 ms 05-01 18:32:13.500 I/TVSMediaServerImpl(19784): ==================================================================== 05-01 18:32:13.500 I/TVSMediaServerImpl(19784): [TVSTORM] tvs_mediaserver started 05-01 18:32:13.500 I/TVSMediaServerImpl(19784): [TVSTORM] version : 4.15.15 05-01 18:32:13.501 I/TVSMediaServerImpl(19784): [TVSTORM] git tag : skb_atv_aml_skb_androidtv_o_based-b3691351dd10f53ff20b2ec1cc4e24f1511a98bd 05-01 18:32:13.501 I/TVSMediaServerImpl(19784): [TVSTORM] build : 2019-04-26.21:35:55 05-01 18:32:13.501 I/TVSMediaServerImpl(19784): -------------------------------------------------------------------- 05-01 18:32:13.501 I/TVSMediaServerImpl(19784): Sources 05-01 18:32:13.501 I/TVSMediaServerImpl(19784): - [common] iptv 05-01 18:32:13.501 I/TVSMediaServerImpl(19784): - [skb] skbfile 05-01 18:32:13.501 I/TVSMediaServerImpl(19784): - [skb] skbiptv 05-01 18:32:13.501 I/TVSMediaServerImpl(19784): - [skb] skbsmv 05-01 18:32:13.501 I/TVSMediaServerImpl(19784): - [skb] skbvod 05-01 18:32:13.501 I/TVSMediaServerImpl(19784): - [common] tsfile 05-01 18:32:13.501 I/TVSMediaServerImpl(19784): -------------------------------------------------------------------- 05-01 18:32:13.501 I/TVSMediaServerImpl(19784): Tuners 05-01 18:32:13.501 I/TVSMediaServerImpl(19784): -------------------------------------------------------------------- 05-01 18:32:13.501 I/TVSMediaServerImpl(19784): ==================================================================== 05-01 18:32:13.501 I/TVSMediaServerImpl(19784): ==================================================================== 05-01 18:32:13.501 I/DummyCasManager(19784): init():35 NOT IMPLEMENTED 05-01 18:32:13.501 D/TVSMediaServerImpl(19784): init() << end 0 ms 05-01 18:32:13.501 D/TVSMMFOptions(19784): TVSMMFOptions() >> begin 05-01 18:32:13.501 E/tvs_common(19784): Failed to get ro.tv_home 05-01 18:32:13.501 D/TVSMediaServerImpl(19784): run() >> begin 05-01 18:32:13.501 D/TVSMMFOptions(19784): load() >> begin 05-01 18:32:13.502 D/TVSMMFOptions(19784): load: scale = fit 05-01 18:32:13.502 D/TVSMMFOptions(19784): set() >> begin 05-01 18:32:13.502 D/TVSMMFOptions(19784): set() << end 0 ms 05-01 18:32:13.502 D/TVSMMFOptions(19784): load: lastframe = true 05-01 18:32:13.502 D/TVSMMFOptions(19784): set() >> begin 05-01 18:32:13.502 D/TVSMMFOptions(19784): set() << end 0 ms 05-01 18:32:13.502 D/TVSMMFOptions(19784): load: monitor = 16x9 05-01 18:32:13.502 D/TVSMMFOptions(19784): set() >> begin 05-01 18:32:13.502 D/TVSMMFOptions(19784): set() << end 0 ms 05-01 18:32:13.502 D/TVSMMFOptions(19784): load: audiomode = pcm 05-01 18:32:13.502 D/TVSMMFOptions(19784): set() >> begin 05-01 18:32:13.502 D/TVSMMFOptions(19784): set() << end 0 ms 05-01 18:32:13.502 D/TVSMMFOptions(19784): load: audiolang = und 05-01 18:32:13.502 D/TVSMMFOptions(19784): set() >> begin 05-01 18:32:13.502 D/TVSMMFOptions(19784): set() << end 0 ms 05-01 18:32:13.502 D/TVSMMFOptions(19784): load: viaudio = false 05-01 18:32:13.502 D/TVSMMFOptions(19784): set() >> begin 05-01 18:32:13.502 D/TVSMMFOptions(19784): set() << end 0 ms 05-01 18:32:13.502 D/TVSMMFOptions(19784): load: viaudiolang = kor 05-01 18:32:13.502 D/TVSMMFOptions(19784): set() >> begin 05-01 18:32:13.502 D/TVSMMFOptions(19784): set() << end 0 ms 05-01 18:32:13.502 E/XmlConfig(19784): No Value in caudio_pid Element. 05-01 18:32:13.502 E/TVSMMFOptions(19784): load: caudio_pid not found 05-01 18:32:13.502 E/XmlConfig(19784): No Value in caudio_codec Element. 05-01 18:32:13.502 E/TVSMMFOptions(19784): load: caudio_codec not found 05-01 18:32:13.502 D/TVSMMFOptions(19784): load: subtitle = false 05-01 18:32:13.502 D/TVSMMFOptions(19784): set() >> begin 05-01 18:32:13.502 D/TVSMMFOptions(19784): set() << end 0 ms 05-01 18:32:13.502 D/TVSMMFOptions(19784): load: subtitlelang = kor 05-01 18:32:13.502 D/TVSMMFOptions(19784): set() >> begin 05-01 18:32:13.502 D/TVSMMFOptions(19784): set() << end 0 ms 05-01 18:32:13.502 D/TVSMMFOptions(19784): load: subtitlefontsize = standard 05-01 18:32:13.502 D/TVSMMFOptions(19784): set() >> begin 05-01 18:32:13.502 D/TVSMMFOptions(19784): set() << end 0 ms 05-01 18:32:13.502 D/TVSMMFOptions(19784): load: iptv_report_jitter = false 05-01 18:32:13.502 D/TVSMMFOptions(19784): set() >> begin 05-01 18:32:13.502 D/TVSMMFOptions(19784): set() << end 0 ms 05-01 18:32:13.502 E/XmlConfig(19784): No Value in satellite0 Element. 05-01 18:32:13.502 E/TVSMMFOptions(19784): load: satellite0 not found 05-01 18:32:13.502 E/XmlConfig(19784): No Value in satellite1 Element. 05-01 18:32:13.502 E/TVSMMFOptions(19784): load: satellite1 not found 05-01 18:32:13.502 E/XmlConfig(19784): No Value in satellite2 Element. 05-01 18:32:13.502 E/TVSMMFOptions(19784): load: satellite2 not found 05-01 18:32:13.502 E/XmlConfig(19784): No Value in satellite15 Element. 05-01 18:32:13.502 E/TVSMMFOptions(19784): load: satellite15 not found 05-01 18:32:13.502 D/TVSMMFOptions(19784): load() << end 1 ms 05-01 18:32:13.502 D/TVSMMFOptions(19784): TVSMMFOptions() << end 1 ms 05-01 18:32:13.510 D/TVSMediaServerImpl(19784): new global event listener: process id(3097), 0xa9409dc4 05-01 18:32:13.510 D/TVSMediaServerImpl(19784): new global event listener: process id(3097), 0xa9409df4 05-01 18:32:13.511 D/TVSMediaServerImpl(19784): createPlayer() >> begin 05-01 18:32:13.511 D/PlayTarget(19784): clear() >> begin 05-01 18:32:13.511 D/PlayTarget(19784): clear() << end 0 ms 05-01 18:32:13.511 D/TVSMediaServerImpl(19784): new global event listener: process id(4081), 0xa9409e6c 05-01 18:32:13.511 D/TVSMediaServerImpl(19784): addSectionListener() >> begin 05-01 18:32:13.511 D/TVSMediaServerImpl(19784): add section listener: pid(4081), 0xa9409ee4 05-01 18:32:13.511 D/TVSMediaServerImpl(19784): addSectionListener() << end 0 ms 05-01 18:32:13.517 D/TVSMediaServerImpl(19784): new global event listener: process id(4020), 0xa9409f14 05-01 18:32:13.529 I/audio-subMixingFactory( 2998): ++usecase_change_validate_l_sm: dev usecase masks = 0, out usecase_masks = 0, out usecase STREAM_PCM_NORMAL 05-01 18:32:13.529 I/audio-subMixingFactory( 2998): ++++usecase_change_validate_l_sm(),continuous_audio_mode 0 dev usecase masks = 0, out usecase_masks = 0, out usecase STRE AM_PCM_NORMAL 05-01 18:32:13.529 I/audio-subMixingFactory( 2998): usecase_change_validate_l_sm(), mixer_aux_buffer_write_sm ! 05-01 18:32:13.529 I/audio-subMixingFactory( 2998): ----usecase_change_validate_l_sm(), continuous_audio_mode 0 dev usecase masks = 0x1, out usecase_masks = 0x1, out usecase STREAM_PCM_NORMAL 05-01 18:32:13.529 I/aml_audio_port( 2998): get_input_port_index(), samplerate 48000 05-01 18:32:13.529 D/aml_audio_port( 2998): new_input_port(), index:0, rbuf size:8192, direct_on:0 05-01 18:32:13.529 D/aml_audio_port( 2998): new_input_port(), fmt 0x1, rate 48000 05-01 18:32:13.529 I/amlaudioMixer( 2998): ++init_mixer_input_port port index 0, size 512 frames 05-01 18:32:13.529 I/amlaudioMixer( 2998): init_mixer_input_port(), port->initial_frames: 151529472 05-01 18:32:13.529 I/aml_audio_port( 2998): get_input_port_index(), samplerate 48000 05-01 18:32:13.529 I/audio-subMixingFactory( 2998): mixer_aux_buffer_write_sm(), primary 0xa8b04000 port index = 0 05-01 18:32:13.529 I/audio-subMixingFactory( 2998): padding_bytes 16384 05-01 18:32:13.529 I/amlaudioMixer( 2998): port index 0 is active now 05-01 18:32:13.529 I/audio-subMixingFactory( 2998): padding_bytes 14336 05-01 18:32:13.529 I/audio-subMixingFactory( 2998): padding_bytes 12288 05-01 18:32:13.529 I/audio-subMixingFactory( 2998): padding_bytes 10240 05-01 18:32:13.529 I/audio-subMixingFactory( 2998): padding_bytes 8192 05-01 18:32:13.529 I/audio-subMixingFactory( 2998): padding_bytes 6144 05-01 18:32:13.530 I/Matrix.Framework(19784): [0.000002 18:32:13.530393 0.000000] Media Engine version 1.7 (Mar 20 2019 13:47:53). (uid: 0) 05-01 18:32:13.535 I/audio-subMixingFactory( 2998): padding_bytes 4096 05-01 18:32:13.535 D/TVSAudioManager(19784): TI2_open() is Opened 05-01 18:32:13.536 D/TVSAudioManager(19784): [addAudioStatusListener] listener=0xa943b3c4, pid=19802 05-01 18:32:13.536 D/TVSMediaServerImpl(19784): player created : id(1), total count(1) 05-01 18:32:13.536 D/TVSMediaServerImpl(19784): dump() >> begin 05-01 18:32:13.536 I/TVSMediaServerImpl(19784): dump():571 NOT IMPLEMENTED 05-01 18:32:13.536 D/TVSMediaServerImpl(19784): dump() << end 0 ms 05-01 18:32:13.536 D/TVSMediaServerImpl(19784): disable auto shutdown flag 05-01 18:32:13.536 D/TVSMediaServerImpl(19784): createPlayer() << end 26 ms 05-01 18:32:13.537 D/TVSGtvPlayer( 3097): TVSGtvPlayer() >> begin 05-01 18:32:13.538 I/audio-subMixingFactory( 2998): padding_bytes 2048 05-01 18:32:13.538 D/TVSGtvPlayer( 3097): TVSGtvPlayer() << end 2 ms 05-01 18:32:13.538 D/TVSGtvPlayer( 3097): initCheck() >> begin 05-01 18:32:13.538 D/TVSGtvPlayer( 3097): initCheck() << end 0 ms 05-01 18:32:13.546 W/amlaudioMixer( 2998): mixer_get_presentation_position(), not valid now 05-01 18:32:13.546 W/audio-subMixingFactory( 2998): out_get_presentation_position_port(), pts not valid yet 05-01 18:32:13.546 D/PlayTarget(19784): clear() >> begin 05-01 18:32:13.546 D/PlayTarget(19784): clear() << end 0 ms 05-01 18:32:13.546 D/PlayTarget(19784): parse() >> begin 05-01 18:32:13.546 D/PlayTarget(19784): uri : skbfile:///data/skb_data/DATA/adv/cache/O0000001033_190425190526.ts 05-01 18:32:13.546 D/PlayTarget(19784): loadSatelliteConfig() >> begin 05-01 18:32:13.546 D/PlayTarget(19784): Satellite config: 05-01 18:32:13.546 D/PlayTarget(19784): loadSatelliteConfig() << end 0 ms 05-01 18:32:13.546 D/PlayTarget(19784): name:, port:0, lnb:0, lnbfh:0, lnbfl:0, tone:0 05-01 18:32:13.546 D/PlayTarget(19784): parse() << end 0 ms 05-01 18:32:13.546 I/PlayTarget(19784): ========================================== 05-01 18:32:13.546 I/PlayTarget(19784): TARGET INFO 05-01 18:32:13.546 I/PlayTarget(19784): ========================================== 05-01 18:32:13.546 I/PlayTarget(19784): uri : skbfile:///data/skb_data/DATA/adv/cache/O0000001033_190425190526.ts 05-01 18:32:13.546 I/PlayTarget(19784): NetworkID : 0x0000 05-01 18:32:13.546 I/PlayTarget(19784): TransportID : 0x0000 05-01 18:32:13.546 I/PlayTarget(19784): ServiceID : 0x0000 05-01 18:32:13.546 I/PlayTarget(19784): CASystemID : 0x0000 05-01 18:32:13.546 I/PlayTarget(19784): CAPid : 0x0000 05-01 18:32:13.546 I/PlayTarget(19784): pcrPid : 0x1FFF 05-01 18:32:13.546 I/PlayTarget(19784): pmtPid : 0x1FFF 05-01 18:32:13.547 I/PlayTarget(19784): pvr path : 05-01 18:32:13.547 I/PlayTarget(19784): isLastFrame : false 05-01 18:32:13.547 I/PlayTarget(19784): isPlayOnPip : false 05-01 18:32:13.547 I/PlayTarget(19784): isMultiview : false 05-01 18:32:13.547 I/PlayTarget(19784): ========================================== 05-01 18:32:13.547 I/BtvMediaPlayer( 8541): [playMedia] after MediaPlayer.setDataSource() 05-01 18:32:13.547 I/BtvMediaPlayer( 8541): [playMedia] before MediaPlayer.prepareAsync() 05-01 18:32:13.548 D/TVSGtvPlayer( 3097): prepareAsync() >> begin 05-01 18:32:13.548 D/TVSGtvPlayer( 3097): prepareAsync() << end 0 ms 05-01 18:32:13.548 I/BtvMediaPlayer( 8541): setState PlaybackState: Preparing 05-01 18:32:13.548 I/BtvMediaPlayer( 8541): [playMedia] after MediaPlayer.prepareAsync() 05-01 18:32:13.548 I/BtvMediaPlayer( 8541): CC Surface showCCSurface() 05-01 18:32:13.549 D/TVSGtvPlayer( 3097): onPrepare() >> begin 05-01 18:32:13.550 D/TSFileSource(19784): TSFileSource() >> begin 05-01 18:32:13.550 D/TSFileSource(19784): TSFileSource() << end 0 ms 05-01 18:32:13.550 D/HalPlayer(19784): HalPlayer() >> begin 05-01 18:32:13.550 D/HalPlayer(19784): HalPlayer() << end 0 ms 05-01 18:32:13.550 D/TSFileSource(19784): onOpen() >> begin 05-01 18:32:13.550 D/TSFileSource(19784): /data/skb_data/DATA/adv/cache/O0000001033_190425190526.ts 05-01 18:32:13.550 D/SurfaceFlinger( 3030): duplicate layer name: changing SurfaceView - com.skb.tv/com.skb.google.tv.main.MainActivity to SurfaceView - com.skb.tv/com.skb.g oogle.tv.main.MainActivity#1 05-01 18:32:13.550 D/TSFileSource(19784): onOpen() << end 0 ms 05-01 18:32:13.550 I/TSFeeder(19784): needFeeder = 1, src=0xa9416390, dst=0xa945046c 05-01 18:32:13.550 D/TSFileSource(19784): onStart() >> begin 05-01 18:32:13.550 D/TSFileSource(19784): onStart() << end 0 ms 05-01 18:32:13.550 D/PMTDetector(19784): detect() >> begin 05-01 18:32:13.551 D/SurfaceFlinger( 3030): duplicate layer name: changing Background for -SurfaceView - com.skb.tv/com.skb.google.tv.main.MainActivity to Background for -Su rfaceView - com.skb.tv/com.skb.google.tv.main.MainActivity#1 05-01 18:32:13.553 D/BtvMediaPlayer( 8541): cc surfaceCreated 05-01 18:32:13.553 D/BtvMediaPlayer( 8541): cc surfaceChanged 05-01 18:32:13.553 I/BtvMediaPlayer( 8541): CC Surface setCCSurface() : Surface(name=null)/@0xe36d681 05-01 18:32:13.553 D/TVSGtvPlayer( 3097): invokeSkb() >> begin 05-01 18:32:13.553 D/TVSGtvPlayer( 3097): INVOKE ID = 1100 05-01 18:32:13.553 D/TVSGtvPlayer( 3097): INVOKE_SKB_SET_SUBTITLE_SURFACE 05-01 18:32:13.554 E/SubtitleCCRenderer( 3097): CC option is empty 05-01 18:32:13.554 D/TVSGtvPlayer( 3097): player is valid 05-01 18:32:13.557 I/TSFeeder(19784): needFeeder = 1, src=0xa9416390, dst=0xa946f010 05-01 18:32:13.557 I/TSFeeder(19784): Feeder start! 05-01 18:32:13.558 I/PMTDetector(19784): Waiting max 2 seconds to detect PMT 05-01 18:32:13.558 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.560 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.560 D/PMTDetector(19784): onTableReceived() >> begin 05-01 18:32:13.560 D/PMTDetector(19784): Finding program number 0, pat program count 1 05-01 18:32:13.560 D/PMTDetector(19784): PAT program number: 1 05-01 18:32:13.560 D/PMTDetector(19784): Select first program: -> 1 05-01 18:32:13.560 I/PMTDetector(19784): TransportId: 0x0001 05-01 18:32:13.560 I/PMTDetector(19784): Starting PMT using pid 0x1e0(480), programNumber(1) 05-01 18:32:13.560 D/PMTDetector(19784): onTableReceived() << end 1 ms 05-01 18:32:13.561 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.563 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.563 D/PMTDetector(19784): onTableReceived() >> begin 05-01 18:32:13.564 I/PMTDetector(19784): Waiting max 2 seconds to detect PMT.. done 05-01 18:32:13.564 D/PMTDetector(19784): onTableReceived() << end 0 ms 05-01 18:32:13.564 I/PMTDetector(19784): ==================================================================== 05-01 18:32:13.564 I/PMTDetector(19784): PMT detect result 05-01 18:32:13.564 I/PMTDetector(19784): -------------------------------------------------------------------- 05-01 18:32:13.564 I/PMTDetector(19784): Detected : true 05-01 18:32:13.564 I/PMTDetector(19784): Program number : 1 05-01 18:32:13.564 I/PMTDetector(19784): PMT pid : 0x1e0 05-01 18:32:13.564 I/PMTDetector(19784): sections : 0xa94211dc 05-01 18:32:13.564 I/PMTDetector(19784): ==================================================================== 05-01 18:32:13.564 D/PMTDetector(19784): detect() << end 14 ms 05-01 18:32:13.564 D/PMTDetector(19784): updatePlayTarget() >> begin 05-01 18:32:13.564 D/PMTDetector(19784): PMT have 2 streams 05-01 18:32:13.565 D/Track (19784): codec = 0x1b(27) VIDEO 05-01 18:32:13.565 I/Stream (19784): [video] pid(0x01E1), type(H264, 0x1b), language(), flags() 05-01 18:32:13.565 D/PlayTarget(19784): added !! type 1 pid(0x1E1) lang() flags() 05-01 18:32:13.565 D/Track (19784): codec = 0xf(15) AUDIO 05-01 18:32:13.565 I/Stream (19784): [audio] pid(0x01E2), type(AAC, 0xf), language(), flags() 05-01 18:32:13.565 I/PMTDetector(19784): ISO639LanguageDescriptor found: 1 05-01 18:32:13.565 I/PMTDetector(19784): ISO639LanguageDescriptor CM found 05-01 18:32:13.565 I/PMTDetector(19784): ISO639LanguageDescriptor language(und) found 05-01 18:32:13.565 D/Track (19784): operator==() >> begin 05-01 18:32:13.565 D/Track (19784): Category : 1 vs 2 05-01 18:32:13.565 D/Track (19784): operator==() << end 0 ms 05-01 18:32:13.565 D/PlayTarget(19784): added !! type 2 pid(0x1E2) lang(und) flags(audio_main ) 05-01 18:32:13.565 D/PMTDetector(19784): PMT have 2 streams 05-01 18:32:13.565 D/PMTDetector(19784): Stream 0: pid - 0x1e1(481), type 0x1b(27) 05-01 18:32:13.565 D/Track (19784): codec = 0x1b(27) VIDEO 05-01 18:32:13.565 D/PlayTarget(19784): added !! type 1 pid(0x1E1) lang() flags() 05-01 18:32:13.565 D/PMTDetector(19784): Stream 1: pid - 0x1e2(482), type 0xf(15) 05-01 18:32:13.565 D/Track (19784): codec = 0xf(15) AUDIO 05-01 18:32:13.565 I/PMTDetector(19784): ISO639LanguageDescriptor found 05-01 18:32:13.565 I/PMTDetector(19784): ISO639LanguageDescriptor CM found 05-01 18:32:13.565 I/PMTDetector(19784): ISO639LanguageDescriptor language(und) found 05-01 18:32:13.565 D/Track (19784): operator==() >> begin 05-01 18:32:13.565 D/Track (19784): Category : 1 vs 2 05-01 18:32:13.565 D/Track (19784): operator==() << end 0 ms 05-01 18:32:13.565 D/PlayTarget(19784): added !! type 2 pid(0x1E2) lang(und) flags(audio_main ) 05-01 18:32:13.565 D/PMTDetector(19784): updatePlayTarget() << end 0 ms 05-01 18:32:13.565 I/PMTCache(19784): PMT updated: pid(0x1e0), version(0x0), :0?pn=1 05-01 18:32:13.565 D/TSFileSource(19784): onRewind() >> begin 05-01 18:32:13.565 D/TSFileSource(19784): onRewind() << end 0 ms 05-01 18:32:13.567 I/PlayTarget(19784): found !! type 1 pid(0x1E1) lang() flags() 05-01 18:32:13.567 I/PlayTarget(19784): found !! type 2 pid(0x1E2) lang(und) flags(audio_main ) 05-01 18:32:13.500 W/tvs_mediaserver(19784): type=1400 audit(0.0:1366): avc: denied { dac_override } for capability=1 scontext=u:r:tvs_mediaserver:s0 tcontext=u:r:tvs_medias erver:s0 tclass=capability permissive=0 05-01 18:32:13.568 D/HalPlayer(19784): open() >> begin 05-01 18:32:13.568 D/AmlHalFactory(19784): createPlayer() >> begin 05-01 18:32:13.568 D/AmlHalPlayer(19784): AmlHalPlayer() >> begin 05-01 18:32:13.568 D/AmlHalPlayer(19784): AmlHalPlayer() << end 0 ms 05-01 18:32:13.568 D/AmlHalFactory(19784): createPlayer() << end 0 ms 05-01 18:32:13.568 D/AmlHalPlayer(19784): open() >> begin 05-01 18:32:13.568 I/AmlHalPlayer(19784): open, 0xa94271f0, zorder=0, pip=0, background=0--- 05-01 18:32:13.568 I/AmlHalPlayer(19784): open, video.isValid()=1, trackCount=1, audio.tracks[0].isValid()=1, isAudioOnlyChannel=0 05-01 18:32:13.568 D/AmlDemux(19784): AmlDemux() >> begin 05-01 18:32:13.568 I/AmlDemux(19784): AmlDemux ------ zorder:0 05-01 18:32:13.568 D/AmlDemux(19784): AmlDemux() << end 0 ms 05-01 18:32:13.568 D/AmlDemux(19784): open() >> begin 05-01 18:32:13.568 D/AmlDemux(19784): Handle: 1 05-01 18:32:13.568 I/MiniTSDemux(19784): [MiniTSDemux:208] 05-01 18:32:13.569 D/AmlDemux(19784): Demux handler:0xa9427280 05-01 18:32:13.569 D/AmlDemux(19784): open() << end 0 ms 05-01 18:32:13.569 D/AmlHalPlayer(19784): Audio buf size: 32768 05-01 18:32:13.569 D/AmlHalPlayer(19784): Video buf size: 4194304 05-01 18:32:13.569 I/PlayerRoster(19784): register idHint:0 05-01 18:32:13.569 D/Chronicle_0(19784): chronicle: birth at: 3672908048 05-01 18:32:13.569 D/AmlHalPlayer(19784): changeStc() >> begin 05-01 18:32:13.569 I/AmlHalPlayer(19784): [changeStc,205], stc.policy=1 05-01 18:32:13.569 D/AmlHalPlayer(19784): FIRST PTS MODE! - but HiSilicon doesnot have this mode. use audio mode instead. 05-01 18:32:13.569 D/AmlHalPlayer(19784): changeStc() << end 0 ms 05-01 18:32:13.569 D/AmlAudio(19784): AmlAudio() >> begin 05-01 18:32:13.569 D/AmlAudio(19784): AmlAudio() << end 0 ms 05-01 18:32:13.569 I/AmlHalPlayer(19784): [open,136], #### zorder=0,audio:0xa942732c 05-01 18:32:13.569 D/AmlAudio(19784): open() >> begin 05-01 18:32:13.570 I/AmlAudio(19784): open--0xa942732c 05-01 18:32:13.570 I/AmlAudio(19784): ======================================================================================== 05-01 18:32:13.570 I/AmlAudio(19784): 05-01 18:32:13.570 I/AmlAudio(19784): dumpAudioInfo: count:1, mute:1, selectedTrackIndex=0 05-01 18:32:13.570 I/AmlAudio(19784): 05-01 18:32:13.570 I/AmlAudio(19784): [ i=0 ] decode=1, codec=0xf, pid=0x1e2 05-01 18:32:13.570 I/AmlAudio(19784): audioFocusedStart:0, trackChanged=0, zorder=0 05-01 18:32:13.570 I/AmlAudio(19784): isAudioOnlyChannel:0, isComposerDataStarted=0 05-01 18:32:13.570 I/AmlAudio(19784): 05-01 18:32:13.570 I/AmlAudio(19784): ======================================================================================== 05-01 18:32:13.570 D/AmlAudio(19784): change() >> begin 05-01 18:32:13.570 I/AmlAudio(19784): ======================================================================================== 05-01 18:32:13.570 I/AmlAudio(19784): 05-01 18:32:13.570 I/AmlAudio(19784): dumpAudioInfo: count:1, mute:1, selectedTrackIndex=0 05-01 18:32:13.570 I/AmlAudio(19784): 05-01 18:32:13.570 I/AmlAudio(19784): [ i=0 ] decode=1, codec=0xf, pid=0x1e2 05-01 18:32:13.570 I/AmlAudio(19784): audioFocusedStart:0, trackChanged=0, zorder=0 05-01 18:32:13.570 I/AmlAudio(19784): isAudioOnlyChannel:0, isComposerDataStarted=0 05-01 18:32:13.570 I/AmlAudio(19784): 05-01 18:32:13.570 I/AmlAudio(19784): ======================================================================================== 05-01 18:32:13.570 D/AmlAudio(19784): mConfig.trackCount:0, mConfig.selectedTrackIndex:-1 05-01 18:32:13.570 D/AmlAudio(19784): Audio track count is different 05-01 18:32:13.570 D/AmlAudio(19784): Need to update tracks 05-01 18:32:13.570 D/AmlAudio(19784): setupCodec() >> begin 05-01 18:32:13.570 D/AmlAudio(19784): Adding audio tracks, count: 1, selected(0) 05-01 18:32:13.570 D/AmlAudio(19784): tracks 0 : pid(0x1e2), codec(0xf -> 0x2) 05-01 18:32:13.570 D/AmlAudio(19784): setupCodec() << end 1 ms 05-01 18:32:13.570 D/AmlAudio(19784): Audio track Selection is different 05-01 18:32:13.570 D/AmlAudio(19784): Select pid(0x1e2), audioFocusedStart=0, trackChanged=0, count=1, zorder=0, isComposerDataStarted=0 05-01 18:32:13.570 D/AmlAudio(19784): doStart() >> begin 05-01 18:32:13.570 D/AmlAudio(19784): doStart() << end 0 ms 05-01 18:32:13.570 D/AmlAudio(19784): setMute() >> begin 05-01 18:32:13.570 I/AmlAudio(19784): #### 0xa942732c setMute: mute=1, mAudioZorder=0 05-01 18:32:13.572 D/AmlAudio(19784): setMute() << end 1 ms 05-01 18:32:13.572 D/AmlAudio(19784): change() << end 2 ms 05-01 18:32:13.572 D/AmlAudio(19784): open() << end 2 ms 05-01 18:32:13.572 D/AmlVideo(19784): AmlVideo() >> begin 05-01 18:32:13.572 D/AmlVideo(19784): AmlVideo() << end 0 ms 05-01 18:32:13.572 D/AmlVideo(19784): open() >> begin 05-01 18:32:13.572 I/AmlVideo(19784): [open,62], isValid=1, codec=0x1b, deocde=1, zorder=0, keepLastFrame=0, isStretch=0 05-01 18:32:13.572 D/AmlVideo(19784): change() >> begin 05-01 18:32:13.572 D/AmlVideo(19784): Setup video, mStarted=0 05-01 18:32:13.572 D/AmlVideo(19784): set Codec, config.zorder=0 05-01 18:32:13.572 D/AmlVideo(19784): set PID: 0x1e1 05-01 18:32:13.572 D/AmlVideo(19784): start() >> begin 05-01 18:32:13.572 D/AmlVideo(19784): doStart() >> begin 05-01 18:32:13.572 D/AmlVideo(19784): doStart() << end 0 ms 05-01 18:32:13.572 D/AmlVideo(19784): start() << end 0 ms 05-01 18:32:13.572 D/AmlVideo(19784): change() << end 0 ms 05-01 18:32:13.572 D/AmlVideo(19784): open() << end 1 ms 05-01 18:32:13.572 I/AmlHalPlayer(19784): set audio output type: 0 05-01 18:32:13.573 I/AmlHalPlayer(19784): set network jitter:300 ms 05-01 18:32:13.573 I/MiniTSDemux(19784): 673 setNetworkJitter:300 ms 05-01 18:32:13.573 I/AmlVideo(19784): arg delayMS = -1 05-01 18:32:13.573 I/AmlAudio(19784): arg delayMS = -1 05-01 18:32:13.573 I/AmlHalPlayer(19784): [open,155], zorder=0, 05-01 18:32:13.573 D/AmlHalPlayer(19784): open() << end 5 ms 05-01 18:32:13.574 I/MiniTSDemux(19784): [setPCRListener:667] pcr listener:0xa940a268 05-01 18:32:13.574 D/HalPlayer(19784): open() << end 5 ms 05-01 18:32:13.575 D/HalPlayer(19784): update() >> begin 05-01 18:32:13.575 D/HalPlayer(19784): No need to update stc 05-01 18:32:13.575 D/HalPlayer(19784): audio need to change: track count, but set again because of multi audio decoder in SKB 05-01 18:32:13.575 I/HalPlayer(19784): Need to change AUDIO 05-01 18:32:13.575 D/AmlAudio(19784): change() >> begin 05-01 18:32:13.575 I/AmlAudio(19784): ======================================================================================== 05-01 18:32:13.575 I/AmlAudio(19784): 05-01 18:32:13.575 I/AmlAudio(19784): dumpAudioInfo: count:1, mute:1, selectedTrackIndex=0 05-01 18:32:13.575 I/AmlAudio(19784): 05-01 18:32:13.575 I/AmlAudio(19784): [ i=0 ] decode=1, codec=0xf, pid=0x1e2 05-01 18:32:13.575 I/AmlAudio(19784): audioFocusedStart:0, trackChanged=0, zorder=0 05-01 18:32:13.575 I/AmlAudio(19784): isAudioOnlyChannel:0, isComposerDataStarted=0 05-01 18:32:13.575 I/AmlAudio(19784): 05-01 18:32:13.575 I/AmlAudio(19784): ======================================================================================== 05-01 18:32:13.575 D/AmlAudio(19784): mConfig.trackCount:1, mConfig.selectedTrackIndex:0 05-01 18:32:13.575 D/AmlAudio(19784): Audio track Selection is SAME 05-01 18:32:13.575 D/AmlAudio(19784): doStart() >> begin 05-01 18:32:13.575 D/AmlAudio(19784): doStart() << end 0 ms 05-01 18:32:13.575 D/AmlAudio(19784): setMute() >> begin 05-01 18:32:13.575 I/AmlAudio(19784): #### 0xa942732c setMute: mute=1, mAudioZorder=0 05-01 18:32:13.575 D/AmlAudio(19784): setMute() << end 0 ms 05-01 18:32:13.575 D/AmlAudio(19784): change() << end 1 ms 05-01 18:32:13.575 D/HalPlayer(19784): No need to update video 05-01 18:32:13.575 D/HalPlayer(19784): update() << end 1 ms 05-01 18:32:13.575 D/TVSGtvPlayer( 3097): onOptionChanged() >> begin 05-01 18:32:13.575 D/TVSGtvPlayer( 3097): onOptionChanged: type=1, value=true 05-01 18:32:13.575 I/SubtitleCCRenderer( 3097): onOptionChanged. key(1) value(true) 05-01 18:32:13.575 D/TVSGtvPlayer( 3097): onOptionChanged() << end 0 ms 05-01 18:32:13.576 I/PlayTarget(19784): found !! type 2 pid(0x1E2) lang(und) flags(selected audio_main ) 05-01 18:32:13.577 I/PlayTarget(19784): found !! type 2 pid(0x1E2) lang(und) flags(selected audio_main ) 05-01 18:32:13.577 I/PlayTarget(19784): found !! type 2 pid(0x1E2) lang(und) flags(selected audio_main ) 05-01 18:32:13.577 D/HalPlayer(19784): getMute() >> begin 05-01 18:32:13.577 D/AmlVideo(19784): getMute() >> begin 05-01 18:32:13.577 I/AmlVideo(19784): getMute():228 NOT IMPLEMENTED 05-01 18:32:13.577 D/AmlVideo(19784): getMute() << end 0 ms 05-01 18:32:13.577 D/AmlAudio(19784): getMute() >> begin 05-01 18:32:13.577 I/AmlAudio(19784): #### 0xa942732c getMute: mute=1 05-01 18:32:13.577 D/AmlAudio(19784): getMute() << end 0 ms 05-01 18:32:13.577 D/HalPlayer(19784): getMute() << end 1 ms 05-01 18:32:13.577 D/HalPlayer(19784): setMute() >> begin 05-01 18:32:13.577 D/AmlVideo(19784): setMute() >> begin 05-01 18:32:13.577 I/AmlVideo(19784): setMute():235 NOT IMPLEMENTED 05-01 18:32:13.577 D/AmlVideo(19784): setMute() << end 0 ms 05-01 18:32:13.577 D/AmlAudio(19784): setMute() >> begin 05-01 18:32:13.577 D/AmlAudio(19784): setMute() << end 0 ms 05-01 18:32:13.577 D/HalPlayer(19784): setMute() << end 0 ms 05-01 18:32:13.578 D/TVSMMFEvent(19784): type=2, what=10000, extra=20007 05-01 18:32:13.578 I/TVSMMFEvent(19784): event: source (1), type(INFO), what(10000), extra(20007) 05-01 18:32:13.578 D/TVSGtvPlayer( 3097): onPlayerEvent() >> begin 05-01 18:32:13.578 D/TVSGtvPlayer( 3097): event : type(2), what(10000), extra(20007) 05-01 18:32:13.578 D/TVSGtvPlayer( 3097): notify INFO : 10000, 20007 05-01 18:32:13.578 D/TVSGtvPlayer( 3097): sendEvent() >> begin 05-01 18:32:13.578 D/TVSGtvPlayer( 3097): onSendEvent() >> begin 05-01 18:32:13.578 D/TVSGtvPlayer( 3097): onSendEvent() << end 1 ms 05-01 18:32:13.578 D/TVSGtvPlayer( 3097): sendEvent() << end 1 ms 05-01 18:32:13.578 D/TVSGtvPlayer( 3097): onPlayerEvent() << end 1 ms 05-01 18:32:13.578 D/TVSMediaServerImpl(19784): onNotifyMediaServerEvent() >> begin 05-01 18:32:13.578 D/TVSMediaServerImpl(19784): onNotifyMediaServerEvent() << end 0 ms 05-01 18:32:13.579 D/TVSMMFEvent(19784): type=2, what=10000, extra=20008 05-01 18:32:13.579 I/TVSMMFEvent(19784): event: source (1), type(INFO), what(10000), extra(20008) 05-01 18:32:13.579 D/TVSGtvPlayer( 3097): sendEvent() >> begin 05-01 18:32:13.579 I/TVSMMFClient( 4081): ===== tvs_mediaserver event callback: 0xad40a52c 05-01 18:32:13.579 D/TVSGtvPlayer( 3097): onSendEvent() >> begin 05-01 18:32:13.579 D/TVSMediaServerImpl(19784): onNotifyMediaServerEvent() >> begin 05-01 18:32:13.579 D/ChannelMediaEventManager( 4081): Event : 10000, Source : 1, URI : skbfile:///data/skb_data/DATA/adv/cache/O0000001033_190425190526.ts 05-01 18:32:13.579 D/TVSGtvPlayer( 3097): onSendEvent() << end 0 ms 05-01 18:32:13.579 D/TVSMediaServerImpl(19784): onNotifyMediaServerEvent() << end 0 ms 05-01 18:32:13.579 D/TVSGtvPlayer( 3097): sendEvent() << end 0 ms 05-01 18:32:13.579 D/TVSGtvPlayer( 3097): updateVideoSurface() >> begin 05-01 18:32:13.579 D/TVSGtvPlayer( 3097): VideoSurface(0x0), NativeHandle(0x0) 05-01 18:32:13.579 D/DatacastingManager( 4081): [371] Event Type : 10000, Source : 1, Event Data(URI) : skbfile:///data/skb_data/DATA/adv/cache/O0000001033_190425190526.ts 05-01 18:32:13.579 D/TVSGtvPlayer( 3097): updateVideoSurface() << end 0 ms 05-01 18:32:13.579 D/TVSGtvPlayer( 3097): onPrepare() << end 29 ms 05-01 18:32:13.579 D/MediaEventHandler( 4081): [114] Event Type : 10000, Source : 1, Event Data(URI) : skbfile:///data/skb_data/DATA/adv/cache/O0000001033_190425190526.ts 05-01 18:32:13.579 D/MediaEventHandler( 4081): [114] Ignore Event Type : 10000 05-01 18:32:13.579 D/TVSGtvPlayer( 3097): invokeSkb() << end 26 ms 05-01 18:32:13.579 I/TVSMMFClient( 4020): ===== tvs_mediaserver event callback: 0xa35b6e3c 05-01 18:32:13.579 I/JNI_Settings( 4020): tvsMediaCallback(10000) 05-01 18:32:13.579 I/JNI_Settings( 4020): what(10000), extra(20007), data size(67) 05-01 18:32:13.579 W/MediaPlayerNative( 8541): info/warning (10000, 20007) 05-01 18:32:13.579 D/TVSGtvPlayer( 3097): onPlayerEvent() >> begin 05-01 18:32:13.579 D/TVSGtvPlayer( 3097): event : type(2), what(10000), extra(20008) 05-01 18:32:13.579 D/TVSGtvPlayer( 3097): notify INFO : 10000, 20008 05-01 18:32:13.579 D/TVSGtvPlayer( 3097): sendEvent() >> begin 05-01 18:32:13.579 D/TVSGtvPlayer( 3097): onSendEvent() >> begin 05-01 18:32:13.580 D/TVSGtvPlayer( 3097): onSendEvent() << end 0 ms 05-01 18:32:13.580 D/TVSGtvPlayer( 3097): sendEvent() << end 0 ms 05-01 18:32:13.580 D/TVSGtvPlayer( 3097): onPlayerEvent() << end 0 ms 05-01 18:32:13.580 D/TVSGtvPlayer( 3097): onOptionChanged() >> begin 05-01 18:32:13.580 D/TVSGtvPlayer( 3097): onOptionChanged: type=10, value=false 05-01 18:32:13.580 I/SubtitleCCRenderer( 3097): onOptionChanged. key(10) value(false) 05-01 18:32:13.580 E/SubtitleCCRenderer( 3097): CC option is empty 05-01 18:32:13.580 D/TVSGtvPlayer( 3097): onOptionChanged() << end 0 ms 05-01 18:32:13.580 D/TVSGtvPlayer( 3097): onOptionChanged() >> begin 05-01 18:32:13.580 D/TVSGtvPlayer( 3097): onOptionChanged: type=12, value=standard 05-01 18:32:13.580 I/SubtitleCCRenderer( 3097): onOptionChanged. key(12) value(standard) 05-01 18:32:13.580 W/MediaPlayerNative( 8541): info/warning (10000, 20008) 05-01 18:32:13.580 I/TVSMMFClient( 4020): ===== tvs_mediaserver event callback: 0xa35b6e3c 05-01 18:32:13.580 I/CCRenderer( 3097): Turn Off the Renderer 05-01 18:32:13.580 I/JNI_Settings( 4020): tvsMediaCallback(10000) 05-01 18:32:13.580 I/JNI_Settings( 4020): what(10000), extra(20008), data size(67) 05-01 18:32:13.580 I/TVSSurfaceAndroid( 3097): Change CC surface 0x0 -> 0xa9925000 05-01 18:32:13.580 I/BtvMediaPlayer( 8541): onInfo what: 10000, extra: 20007 05-01 18:32:13.580 I/TVSMMFClient( 4081): ===== tvs_mediaserver event callback: 0xad40a52c 05-01 18:32:13.581 D/ChannelMediaEventManager( 4081): Event : 10000, Source : 1, URI : skbfile:///data/skb_data/DATA/adv/cache/O0000001033_190425190526.ts 05-01 18:32:13.581 D/DatacastingManager( 4081): [371] Event Type : 10000, Source : 1, Event Data(URI) : skbfile:///data/skb_data/DATA/adv/cache/O0000001033_190425190526.ts 05-01 18:32:13.582 D/MediaEventHandler( 4081): [114] Event Type : 10000, Source : 1, Event Data(URI) : skbfile:///data/skb_data/DATA/adv/cache/O0000001033_190425190526.ts 05-01 18:32:13.582 D/MediaEventHandler( 4081): [114] Ignore Event Type : 10000 05-01 18:32:13.582 I/[Gralloc]( 3007): framebuffer hal alread move to hwcomposer 05-01 18:32:13.591 D/TVSGtvPlayer( 3097): onOptionChanged() << end 11 ms 05-01 18:32:13.591 D/TVSGtvPlayer( 3097): invokeSkb() >> begin 05-01 18:32:13.591 D/TVSGtvPlayer( 3097): INVOKE ID = 1 05-01 18:32:13.591 D/TVSGtvPlayer( 3097): onGetTrackInfo() >> begin 05-01 18:32:13.592 D/TVSGtvPlayer( 3097): Language : und 05-01 18:32:13.592 D/TVSGtvPlayer( 3097): Track Info : Mime(video/avc), language(und), pid(481) 05-01 18:32:13.592 I/[Gralloc]( 3007): framebuffer hal alread move to hwcomposer 05-01 18:32:13.592 D/TVSGtvPlayer( 3097): Language : und 05-01 18:32:13.592 D/TVSGtvPlayer( 3097): Track Info : Mime(audio/mp4a-latm main), language(und), pid(482) 05-01 18:32:13.592 D/TVSGtvPlayer( 3097): onGetTrackInfo() << end 1 ms 05-01 18:32:13.592 D/TVSGtvPlayer( 3097): invokeSkb() << end 1 ms 05-01 18:32:13.592 E/gralloc ( 3007): ioctl c0144900 failed with code -1: Operation not permitted 05-01 18:32:13.592 I/BtvMediaPlayer( 8541): onPrepared 05-01 18:32:13.592 I/BtvMediaPlayer( 8541): setState PlaybackState: Prepared 05-01 18:32:13.593 I/BtvMediaPlayer( 8541): onInfo what: 10000, extra: 20008 05-01 18:32:13.593 I/BtvMediaPlayer( 8541): [onPrepared] before MediaPlayer.setDisplay() 05-01 18:32:13.593 D/SurfaceUtils( 3097): connecting to surface 0xa9953008, reason setVideoSurfaceTexture 05-01 18:32:13.594 D/TVSGtvPlayer( 3097): setVideoSurfaceTexture() >> begin 05-01 18:32:13.594 D/TVSGtvPlayer( 3097): setVideoSurfaceTexture(): bufferProducer=0xac390620 05-01 18:32:13.594 D/TVSGtvPlayer( 3097): updateVideoSurface() >> begin 05-01 18:32:13.594 D/HalPlayer(19784): getSidebandHandle() >> begin 05-01 18:32:13.594 D/AmlHalPlayer(19784): getSidebandHandle() >> begin 05-01 18:32:13.594 D/AmlVideo(19784): getSidebandHandle() >> begin 05-01 18:32:13.594 D/AmlVideo(19784): getSidebandHandle() << end 0 ms 05-01 18:32:13.594 D/AmlHalPlayer(19784): getSidebandHandle() << end 0 ms 05-01 18:32:13.594 D/HalPlayer(19784): getSidebandHandle() << end 0 ms 05-01 18:32:13.594 D/TVSGtvPlayer( 3097): VideoSurface(0xa9953800), NativeHandle(0xac3ce6a0) 05-01 18:32:13.595 D/TVSGtvPlayer( 3097): Linking native handle to surface 05-01 18:32:13.595 D/TVSGtvPlayer( 3097): updateVideoSurface() << end 1 ms 05-01 18:32:13.595 D/TVSGtvPlayer( 3097): setVideoSurfaceTexture() << end 1 ms 05-01 18:32:13.595 I/BtvMediaPlayer( 8541): [onPrepared] after MediaPlayer.setDisplay() 05-01 18:32:13.595 I/BtvMediaPlayer( 8541): [onPrepared] before MediaPlayer.start() 05-01 18:32:13.595 D/TVSGtvPlayer( 3097): setLooping() >> begin 05-01 18:32:13.596 D/TVSGtvPlayer( 3097): WE DONT SUPPORT THIS 05-01 18:32:13.596 D/TVSGtvPlayer( 3097): setLooping() << end 0 ms 05-01 18:32:13.596 E/bt_btif ( 3371): register_notification_rsp: Avrcp device is not connected, handle: 0x0 05-01 18:32:13.596 E/bt_btif ( 3371): register_notification_rsp: Avrcp device is not connected, handle: 0x0 05-01 18:32:13.597 V/MediaRouter( 3253): onRestoreRoute() : route=RouteInfo{ name=HDMI, description=null, status=null, category=RouteCategory{ name=시스템 types=ROUTE_TYPE_L IVE_AUDIO ROUTE_TYPE_LIVE_VIDEO groupable=false }, supportedTypes=ROUTE_TYPE_LIVE_AUDIO ROUTE_TYPE_LIVE_VIDEO , presentationDisplay=null } 05-01 18:32:13.597 V/MediaRouter( 3253): Selecting route: RouteInfo{ name=HDMI, description=null, status=null, category=RouteCategory{ name=시스템 types=ROUTE_TYPE_LIVE_AUDI O ROUTE_TYPE_LIVE_VIDEO groupable=false }, supportedTypes=ROUTE_TYPE_LIVE_AUDIO ROUTE_TYPE_LIVE_VIDEO , presentationDisplay=null } 05-01 18:32:13.598 D/TVSGtvPlayer( 3097): setLooping() >> begin 05-01 18:32:13.598 D/TVSGtvPlayer( 3097): WE DONT SUPPORT THIS 05-01 18:32:13.598 D/TVSGtvPlayer( 3097): setLooping() << end 0 ms 05-01 18:32:13.598 D/TVSGtvPlayer( 3097): start() >> begin 05-01 18:32:13.598 D/TVSGtvPlayer( 3097): onStart() >> begin 05-01 18:32:13.598 D/HalPlayer(19784): start() >> begin 05-01 18:32:13.598 D/AmlHalPlayer(19784): start() >> begin 05-01 18:32:13.598 D/AmlDemux(19784): start() >> begin 05-01 18:32:13.598 I/MiniTSDemux(19784): [start:376] 05-01 18:32:13.598 D/AmlDemux(19784): start() << end 0 ms 05-01 18:32:13.598 D/AmlAudio(19784): start() >> begin 05-01 18:32:13.598 D/AmlAudio(19784): doStart() >> begin 05-01 18:32:13.598 D/AmlAudio(19784): doStart() << end 0 ms 05-01 18:32:13.598 D/AmlAudio(19784): start() << end 0 ms 05-01 18:32:13.599 I/MiniDataSource(19784): default init READ_WAIT_LONG ==> 10000 ms 05-01 18:32:13.599 I/MiniDataSource(19784): default init READ_WAIT_NORMAL ==> 2000 ms 05-01 18:32:13.599 I/FdDataSource(19784): init mFd:15 05-01 18:32:13.599 I/FdDataSource(19784): pipe source! initial size:0 05-01 18:32:13.599 I/FdDataSource(19784): notify_fd:16 05-01 18:32:13.602 D/AmlHalPlayer(19784): start() << end 4 ms 05-01 18:32:13.602 D/HalPlayer(19784): start() << end 4 ms 05-01 18:32:13.602 D/AmlDemux(19784): onSectionFilterAdd() >> begin 05-01 18:32:13.602 D/AmlDemux(19784): =============================================================================== 05-01 18:32:13.602 D/AmlDemux(19784): filter config: handle(1), pid(0x0, 0) 05-01 18:32:13.602 D/AmlDemux(19784): =============================================================================== 05-01 18:32:13.602 D/AmlDemux(19784): TVSTORM 05-01 18:32:13.602 D/AmlDemux(19784): =============================================================================== 05-01 18:32:13.602 I/MiniTSDemux(19784): [createChannel:258] new channel:0xa9421298, pid = 0 05-01 18:32:13.602 I/MiniTSDemux(19784): add section pid:0(0) 05-01 18:32:13.603 I/MiniTSDemux(19784): [attachFilter:352] attach filter 0xa940a178 to channel:0xa9421298(pid=0) 05-01 18:32:13.603 D/AmlDemux(19784): onSectionFilterAdd() << end 1 ms 05-01 18:32:13.603 D/AmlDemux(19784): onSectionFilterControl() >> begin 05-01 18:32:13.604 D/AmlDemux(19784): handle(1), true 05-01 18:32:13.604 D/AmlDemux(19784): onSectionFilterControl() << end 1 ms 05-01 18:32:13.605 D/AmlDemux(19784): onSectionFilterAdd() >> begin 05-01 18:32:13.605 D/AmlDemux(19784): =============================================================================== 05-01 18:32:13.605 D/AmlDemux(19784): filter config: handle(2), pid(0x1e0, 480) 05-01 18:32:13.605 D/AmlDemux(19784): =============================================================================== 05-01 18:32:13.605 D/AmlDemux(19784): TVSTORM 05-01 18:32:13.605 D/AmlDemux(19784): =============================================================================== 05-01 18:32:13.605 I/MiniTSDemux(19784): [createChannel:258] new channel:0xa94212c0, pid = 480 05-01 18:32:13.605 I/MiniTSDemux(19784): add section pid:480(0x1e0) 05-01 18:32:13.605 W/MiniTSParser(19784): add section pid:480(0x1e0) 05-01 18:32:13.605 I/MiniTSDemux(19784): [attachFilter:352] attach filter 0xa940a1f0 to channel:0xa94212c0(pid=480) 05-01 18:32:13.605 D/AmlDemux(19784): onSectionFilterAdd() << end 0 ms 05-01 18:32:13.605 D/AmlDemux(19784): onSectionFilterControl() >> begin 05-01 18:32:13.605 D/AmlDemux(19784): handle(2), true 05-01 18:32:13.605 D/AmlDemux(19784): onSectionFilterControl() << end 0 ms 05-01 18:32:13.605 I/TSFeeder(19784): Feeder start! 05-01 18:32:13.605 D/TVSMMFEvent(19784): type=2, what=10001, extra=0 05-01 18:32:13.605 I/TVSMMFEvent(19784): event: source (1), type(INFO), what(10001), extra(0) 05-01 18:32:13.606 D/HalPlayer(19784): getMute() >> begin 05-01 18:32:13.606 D/AmlVideo(19784): getMute() >> begin 05-01 18:32:13.606 I/AmlVideo(19784): getMute():228 NOT IMPLEMENTED 05-01 18:32:13.606 D/AmlVideo(19784): getMute() << end 0 ms 05-01 18:32:13.606 D/AmlAudio(19784): getMute() >> begin 05-01 18:32:13.606 D/TVSMediaServerImpl(19784): onNotifyMediaServerEvent() >> begin 05-01 18:32:13.606 D/TVSGtvPlayer( 3097): onPlayerEvent() >> begin 05-01 18:32:13.606 D/TVSGtvPlayer( 3097): event : type(2), what(10001), extra(0) 05-01 18:32:13.606 D/TVSGtvPlayer( 3097): notify INFO : 10001, 0 05-01 18:32:13.606 D/TVSGtvPlayer( 3097): sendEvent() >> begin 05-01 18:32:13.606 D/TVSMediaServerImpl(19784): onNotifyMediaServerEvent() << end 1 ms 05-01 18:32:13.606 D/TVSGtvPlayer( 3097): onSendEvent() >> begin 05-01 18:32:13.606 D/TVSGtvPlayer( 3097): onSendEvent() << end 0 ms 05-01 18:32:13.606 D/TVSGtvPlayer( 3097): sendEvent() << end 0 ms 05-01 18:32:13.606 D/TVSGtvPlayer( 3097): onPlayerEvent() << end 1 ms 05-01 18:32:13.606 I/TVSMMFClient( 4081): ===== tvs_mediaserver event callback: 0xad40a52c 05-01 18:32:13.607 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.607 D/ChannelMediaEventManager( 4081): Event : 10001, Source : 1, URI : skbfile:///data/skb_data/DATA/adv/cache/O0000001033_190425190526.ts 05-01 18:32:13.607 D/DatacastingManager( 4081): [371] Event Type : 10001, Source : 1, Event Data(URI) : skbfile:///data/skb_data/DATA/adv/cache/O0000001033_190425190526.ts 05-01 18:32:13.607 D/MediaEventHandler( 4081): [114] Event Type : 10001, Source : 1, Event Data(URI) : skbfile:///data/skb_data/DATA/adv/cache/O0000001033_190425190526.ts 05-01 18:32:13.607 E/MiniTSParser(19784): first pcr:30608851(1133661) 05-01 18:32:13.607 I/TVSMMFClient( 4020): ===== tvs_mediaserver event callback: 0xa35b6e3c 05-01 18:32:13.607 I/JNI_Settings( 4020): tvsMediaCallback(10001) 05-01 18:32:13.608 I/JNI_Settings( 4020): what(10001), extra(0), data size(67) 05-01 18:32:13.608 I/PSIMonitor(19784): Table Received: PAT - ver(0), pid 0x0(0), tid 0x0(0) 1 sections 05-01 18:32:13.608 I/PSIMonitor(19784): Table Received: PMT - ver(0), pid 0x1e0(480), tid 0x2(2) 1 sections 05-01 18:32:13.608 D/AmlDemux(19784): onSectionFilterAdd() >> begin 05-01 18:32:13.608 D/Chronicle_0(19784): chronicle: parsing start at 3672947311, 39.3 ms from birth 05-01 18:32:13.608 D/AmlDemux(19784): =============================================================================== 05-01 18:32:13.608 D/AmlDemux(19784): filter config: handle(3), pid(0x1, 1) 05-01 18:32:13.608 D/AmlDemux(19784): =============================================================================== 05-01 18:32:13.608 D/AmlDemux(19784): TVSTORM 05-01 18:32:13.608 D/AmlDemux(19784): =============================================================================== 05-01 18:32:13.608 I/MiniTSDemux(19784): [createChannel:258] new channel:0xa9421388, pid = 1 05-01 18:32:13.608 I/MiniTSDemux(19784): add section pid:1(0x1) 05-01 18:32:13.609 I/MiniTSDemux(19784): [attachFilter:352] attach filter 0xa940a3b8 to channel:0xa9421388(pid=1) 05-01 18:32:13.609 D/AmlDemux(19784): onSectionFilterAdd() << end 0 ms 05-01 18:32:13.609 D/AmlDemux(19784): onSectionFilterControl() >> begin 05-01 18:32:13.609 D/AmlDemux(19784): handle(3), true 05-01 18:32:13.609 D/AmlDemux(19784): onSectionFilterControl() << end 0 ms 05-01 18:32:13.609 I/AmlAudio(19784): #### 0xa942732c getMute: mute=1 05-01 18:32:13.609 D/AmlAudio(19784): getMute() << end 4 ms 05-01 18:32:13.609 D/HalPlayer(19784): getMute() << end 4 ms 05-01 18:32:13.609 D/HalPlayer(19784): setMute() >> begin 05-01 18:32:13.609 D/AmlVideo(19784): setMute() >> begin 05-01 18:32:13.609 I/AmlVideo(19784): setMute():235 NOT IMPLEMENTED 05-01 18:32:13.609 D/AmlVideo(19784): setMute() << end 0 ms 05-01 18:32:13.609 D/AmlAudio(19784): setMute() >> begin 05-01 18:32:13.609 D/AmlAudio(19784): setMute() << end 0 ms 05-01 18:32:13.609 D/HalPlayer(19784): setMute() << end 0 ms 05-01 18:32:13.610 D/TVSGtvPlayer( 3097): onStart() << end 12 ms 05-01 18:32:13.610 D/PMTDetector(19784): Finding program number 1, pat program count 1 05-01 18:32:13.610 D/PMTDetector(19784): PAT program number: 1 05-01 18:32:13.610 D/PMTDetector(19784): Found : 1 05-01 18:32:13.610 D/TVSGtvPlayer( 3097): sendEvent() >> begin 05-01 18:32:13.610 D/PMTDetector(19784): updatePlayTarget() >> begin 05-01 18:32:13.610 D/TVSGtvPlayer( 3097): onSendEvent() >> begin 05-01 18:32:13.610 D/PMTDetector(19784): PMT have 2 streams 05-01 18:32:13.610 D/Track (19784): codec = 0x1b(27) VIDEO 05-01 18:32:13.610 I/Stream (19784): [video] pid(0x01E1), type(H264, 0x1b), language(), flags() 05-01 18:32:13.610 D/PlayTarget(19784): added !! type 1 pid(0x1E1) lang() flags() 05-01 18:32:13.610 D/TVSGtvPlayer( 3097): onSendEvent() << end 1 ms 05-01 18:32:13.610 D/Track (19784): codec = 0xf(15) AUDIO 05-01 18:32:13.610 D/TVSGtvPlayer( 3097): sendEvent() << end 1 ms 05-01 18:32:13.610 D/TVSGtvPlayer( 3097): start() << end 13 ms 05-01 18:32:13.610 I/Stream (19784): [audio] pid(0x01E2), type(AAC, 0xf), language(), flags() 05-01 18:32:13.610 I/PMTDetector(19784): ISO639LanguageDescriptor found: 1 05-01 18:32:13.610 I/PMTDetector(19784): ISO639LanguageDescriptor CM found 05-01 18:32:13.610 I/PMTDetector(19784): ISO639LanguageDescriptor language(und) found 05-01 18:32:13.610 D/Track (19784): operator==() >> begin 05-01 18:32:13.610 D/Track (19784): Category : 1 vs 2 05-01 18:32:13.610 D/Track (19784): operator==() << end 0 ms 05-01 18:32:13.610 D/PlayTarget(19784): added !! type 2 pid(0x1E2) lang(und) flags(audio_main ) 05-01 18:32:13.610 D/PMTDetector(19784): PMT have 2 streams 05-01 18:32:13.610 D/PMTDetector(19784): Stream 0: pid - 0x1e1(481), type 0x1b(27) 05-01 18:32:13.610 D/Track (19784): codec = 0x1b(27) VIDEO 05-01 18:32:13.610 D/PlayTarget(19784): added !! type 1 pid(0x1E1) lang() flags() 05-01 18:32:13.610 D/PMTDetector(19784): Stream 1: pid - 0x1e2(482), type 0xf(15) 05-01 18:32:13.610 D/Track (19784): codec = 0xf(15) AUDIO 05-01 18:32:13.610 I/PMTDetector(19784): ISO639LanguageDescriptor found 05-01 18:32:13.610 I/PMTDetector(19784): ISO639LanguageDescriptor CM found 05-01 18:32:13.610 I/PMTDetector(19784): ISO639LanguageDescriptor language(und) found 05-01 18:32:13.610 I/BtvMediaPlayer( 8541): [onPrepared] after MediaPlayer.start() 05-01 18:32:13.610 D/Track (19784): operator==() >> begin 05-01 18:32:13.610 D/Track (19784): Category : 1 vs 2 05-01 18:32:13.610 D/Track (19784): operator==() << end 0 ms 05-01 18:32:13.610 D/PlayTarget(19784): added !! type 2 pid(0x1E2) lang(und) flags(audio_main ) 05-01 18:32:13.610 I/BtvMediaPlayer( 8541): setState PlaybackState: Started 05-01 18:32:13.610 D/PMTDetector(19784): updatePlayTarget() << end 1 ms 05-01 18:32:13.611 W/MediaPlayerNative( 8541): info/warning (10001, 0) 05-01 18:32:13.611 I/PlayTarget(19784): found !! type 1 pid(0x1E1) lang() flags() 05-01 18:32:13.611 I/PlayTarget(19784): found !! type 2 pid(0x1E2) lang(und) flags(audio_main ) 05-01 18:32:13.611 D/HalPlayer(19784): update() >> begin 05-01 18:32:13.611 D/HalPlayer(19784): No need to update stc 05-01 18:32:13.611 D/HalPlayer(19784): audio need to change: track count, but set again because of multi audio decoder in SKB 05-01 18:32:13.611 I/HalPlayer(19784): Need to change AUDIO 05-01 18:32:13.611 D/AmlAudio(19784): change() >> begin 05-01 18:32:13.611 I/AmlAudio(19784): ======================================================================================== 05-01 18:32:13.611 I/AmlAudio(19784): 05-01 18:32:13.611 I/AmlAudio(19784): dumpAudioInfo: count:1, mute:1, selectedTrackIndex=0 05-01 18:32:13.611 I/AmlAudio(19784): 05-01 18:32:13.611 I/AmlAudio(19784): [ i=0 ] decode=1, codec=0xf, pid=0x1e2 05-01 18:32:13.611 I/AmlAudio(19784): audioFocusedStart:0, trackChanged=0, zorder=0 05-01 18:32:13.611 I/AmlAudio(19784): isAudioOnlyChannel:0, isComposerDataStarted=0 05-01 18:32:13.611 I/AmlAudio(19784): 05-01 18:32:13.611 I/AmlAudio(19784): ======================================================================================== 05-01 18:32:13.611 D/AmlAudio(19784): mConfig.trackCount:1, mConfig.selectedTrackIndex:0 05-01 18:32:13.611 D/AmlAudio(19784): Audio track Selection is SAME 05-01 18:32:13.611 D/AmlAudio(19784): doStart() >> begin 05-01 18:32:13.611 D/AmlAudio(19784): doStart() << end 0 ms 05-01 18:32:13.611 D/AmlAudio(19784): setMute() >> begin 05-01 18:32:13.612 I/AmlAudio(19784): #### 0xa942732c setMute: mute=1, mAudioZorder=0 05-01 18:32:13.612 D/AmlAudio(19784): setMute() << end 0 ms 05-01 18:32:13.612 D/AmlAudio(19784): change() << end 0 ms 05-01 18:32:13.612 D/HalPlayer(19784): No need to update video 05-01 18:32:13.612 D/HalPlayer(19784): update() << end 0 ms 05-01 18:32:13.612 D/TVSMMFEvent(19784): type=2, what=10000, extra=20007 05-01 18:32:13.612 I/TVSMMFEvent(19784): event: source (1), type(INFO), what(10000), extra(20007) 05-01 18:32:13.612 D/TVSGtvPlayer( 3097): onPlayerEvent() >> begin 05-01 18:32:13.612 D/TVSGtvPlayer( 3097): event : type(2), what(10000), extra(20007) 05-01 18:32:13.612 D/TVSGtvPlayer( 3097): notify INFO : 10000, 20007 05-01 18:32:13.612 D/TVSGtvPlayer( 3097): sendEvent() >> begin 05-01 18:32:13.612 D/TVSGtvPlayer( 3097): onSendEvent() >> begin 05-01 18:32:13.612 D/TVSGtvPlayer( 3097): onSendEvent() << end 0 ms 05-01 18:32:13.612 D/TVSGtvPlayer( 3097): sendEvent() << end 1 ms 05-01 18:32:13.612 D/TVSGtvPlayer( 3097): onPlayerEvent() << end 1 ms 05-01 18:32:13.612 W/MediaPlayerNative( 8541): info/warning (10000, 20007) 05-01 18:32:13.613 D/TVSMediaServerImpl(19784): onNotifyMediaServerEvent() >> begin 05-01 18:32:13.613 D/TVSMediaServerImpl(19784): onNotifyMediaServerEvent() << end 0 ms 05-01 18:32:13.613 I/TVSMMFClient( 4020): ===== tvs_mediaserver event callback: 0xa35b6e3c 05-01 18:32:13.613 I/JNI_Settings( 4020): tvsMediaCallback(10000) 05-01 18:32:13.613 I/JNI_Settings( 4020): what(10000), extra(20007), data size(67) 05-01 18:32:13.613 I/TVSMMFClient( 4081): ===== tvs_mediaserver event callback: 0xad40a52c 05-01 18:32:13.613 D/ChannelMediaEventManager( 4081): Event : 10000, Source : 1, URI : skbfile:///data/skb_data/DATA/adv/cache/O0000001033_190425190526.ts 05-01 18:32:13.613 D/DatacastingManager( 4081): [371] Event Type : 10000, Source : 1, Event Data(URI) : skbfile:///data/skb_data/DATA/adv/cache/O0000001033_190425190526.ts 05-01 18:32:13.613 I/MiniDataSource(19784): init ReadWaitType: READ_WAIT_NORMAL ==> 2000 ms 05-01 18:32:13.613 D/MediaEventHandler( 4081): [114] Event Type : 10000, Source : 1, Event Data(URI) : skbfile:///data/skb_data/DATA/adv/cache/O0000001033_190425190526.ts 05-01 18:32:13.613 D/MediaEventHandler( 4081): [114] Ignore Event Type : 10000 05-01 18:32:13.614 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.616 I/MiniCodec(19784): find codec meta, hwsync = 0 05-01 18:32:13.616 I/MiniNDKMediaCodec(19784): new MiniNDKMediaCodec 05-01 18:32:13.616 D/MiniAudioSystem(19784): [create:16] 05-01 18:32:13.616 I/MiniAudioSystem(19784): new MiniAudioSystem 05-01 18:32:13.616 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.616 D/AudioSystemWrapper(19784): [AudioSystemWrapper:15] 05-01 18:32:13.617 I/MiniNDKMediaCodec(19784): new MiniNDKMediaCodec 05-01 18:32:13.619 I/BtvMediaPlayer( 8541): surfaceChanged format:4, width:1920, height:1080 05-01 18:32:13.619 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.621 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.622 D/STBAPIManager( 8541): getKidsZoneEntry called 05-01 18:32:13.622 D/STBAPIManager( 8541): getProperty() key : KIDS_MODE_ENTRY, result : 0 05-01 18:32:13.622 D/KidszoneManager( 8541): Kidszone is false 05-01 18:32:13.622 D/PromotionPlayer( 8541): Promotion onStart 05-01 18:32:13.622 I/CompanionProperty( 8541): setCompanionProperties() called 05-01 18:32:13.623 D/CompanionProperty( 8541): setCompanionProperties() end 05-01 18:32:13.623 D/CompanionProperty( 8541): PlayMode:2 05-01 18:32:13.623 I/HomeAdManager( 8541): sendHomePromotionLog() called. homePromotionLogType : PROMOTION_HOME_LOG_START 05-01 18:32:13.623 D/FragmentControl( 8541): isUIVisibility() baseFragment : G2TvFragment{25f6f43 #1 id=0x7f08049e} 05-01 18:32:13.623 I/G2TvFragment( 8541): isFragmentHidden() isFragmentHidden : false 05-01 18:32:13.623 D/FragmentControl( 8541): isUIVisibility() G2TvFragment is VISIBLE ? true 05-01 18:32:13.623 D/FragmentControl( 8541): isUIVisibility() isHidden : false 05-01 18:32:13.623 D/FragmentControl( 8541): isUIVisibility() isUIVisibility : true 05-01 18:32:13.623 D/HomeAdManager( 8541): isUIVisibility : true 05-01 18:32:13.623 D/HomeAdManager( 8541): logInfo.m_oLogFlagBoolean : true 05-01 18:32:13.623 I/BtvMediaPlayer( 8541): onInfo what: 10001, extra: 0 05-01 18:32:13.623 I/BtvMediaPlayer( 8541): onInfo what: 10000, extra: 20007 05-01 18:32:13.626 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.628 I/TVSAudioManager(19784): checkAudioStatus(): android::AudioSystem::setParameters("btv_playback=on") 05-01 18:32:13.629 I/TVSAudioManager(19784): dump(): devices=0x00000400, mAudioOutputType=0, mBtvPlayback=1 05-01 18:32:13.634 I/OMXClient(19784): IOmx service obtained 05-01 18:32:13.634 I/OMXMaster( 3114): makeComponentInstance(OMX.google.aac.decoder) in omx@1.0-service process 05-01 18:32:13.635 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.637 V/MiniNDKMediaCodec(19784): private ctor 05-01 18:32:13.637 I/AmAVUtils(19784): AmAVUtils::getComponentRole isEncoder :0 mime:audio/mp4a-latm 05-01 18:32:13.637 V/AVUtils (19784): AVUtils::getComponentRole 05-01 18:32:13.639 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.642 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.643 I/AmAVUtils(19784): AmAVUtils::isAudioExtendFormat input = audio/mp4a-latm 05-01 18:32:13.644 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.644 E/OMXNodeInstance( 3114): getConfig(0xaa21cba0:google.aac.decoder, ConfigAndroidVendorExtension(0x6f100004)) ERROR: Undefined(0x80001001) 05-01 18:32:13.646 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.652 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.654 I/OMXClient(19784): IOmx service obtained 05-01 18:32:13.654 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.655 I/OMXMaster( 3114): makeComponentInstance(OMX.amlogic.avc.decoder.awesome) in omx@1.0-service process 05-01 18:32:13.655 D/OmxComponentManagerImpl( 3114): support frame mode 05-01 18:32:13.655 D/OmxComponentManagerImpl( 3114): format support multi-instance 05-01 18:32:13.655 D/OmxComponentManagerImpl( 3114): getEntryByName_2_num=0, componentName:OMX.amlogic.avc.decoder.awesome 05-01 18:32:13.656 V/AmlogicVideoDecoderAwesome( 3114): AmlogicVideoDecoder:99 > 05-01 18:32:13.656 V/AmlogicVideoDecoderAwesome( 3114): initializeState_l:1528 > 05-01 18:32:13.656 V/AmlogicVideoDecoderAwesome( 3114): media.omx.latency is not set used def=1.000000 05-01 18:32:13.656 D/AmlogicVideoDecoderAwesome( 3114): AmlogicVideoDecoder, mOmxInstancenum 1, mOmxInstanceID 216 05-01 18:32:13.656 W/AmlSysfsUtil( 3114): [amsysfs_get_sysfs_str] /sys/module/am_vecm/parameters/dolby_vision_enable failed! 05-01 18:32:13.656 D/AmlogicVideoDecoderAwesome( 3114): mCheckDvesDone = 1 05-01 18:32:13.656 D/OmxVideoDecoder( 3114): drm use frame_mode 05-01 18:32:13.656 D/OmxVideoDecoder( 3114): libsecmem.so is V2 05-01 18:32:13.656 E/secmem_tz( 3114): [Secure_NegotiateVersion:470] Secure_NegotiateVersion 2 2 2 2 2 05-01 18:32:13.656 E/secmem_tz( 3114): [Secure_NegotiateVersion:478] Negotiated secmem version = 2 05-01 18:32:13.656 I/OmxVideoDecoder( 3114): we will use V2 05-01 18:32:13.657 W/OmxLogConf( 3114): Can not read property media.omx.log_levels, using 0 05-01 18:32:13.657 V/AmlogicVideoDecoderAwesome( 3114): media.omx.out_buffer1 is not set used def=4 05-01 18:32:13.657 D/OmxVideoDecoder( 3114): setInputCoding OMX.amlogic.avc.decoder.awesome 05-01 18:32:13.657 D/OmxVideoDecoder( 3114): mIsLinux49=1, major=4, minor=9 05-01 18:32:13.657 D/OmxVideoDecoder( 3114): multi dec exist 05-01 18:32:13.657 D/OmxVideoDecoder( 3114): Use frame mode for multidec. 05-01 18:32:13.657 D/OmxVideoDecoder( 3114): Use min out buffer size 05-01 18:32:13.657 D/OmxVideoDecoder( 3114): mSupportPiPVideo = 1 05-01 18:32:13.657 D/OmxVideoDecoder( 3114): mMustOsdDisplay 0, mAmVideoUsed=0, mSupportPiPVideo=1, mPipVideoUsed=0, mVideoUsedByOmx=0 05-01 18:32:13.658 D/OMX-VENDOR-EXT( 3114): OMX.amlogic.avc.decoder.awesome : Vendor extensions supported (7) 05-01 18:32:13.658 D/OMX-VENDOR-EXT( 3114): vendor.4k-osd{{ enable : Int32 }, } 05-01 18:32:13.658 D/OMX-VENDOR-EXT( 3114): vendor.is-mvc{{ enable : Int32 }, } 05-01 18:32:13.658 D/OMX-VENDOR-EXT( 3114): vendor.is-flv{{ nContainIndex : Int32 }, } 05-01 18:32:13.658 D/OMX-VENDOR-EXT( 3114): vendor.dvconfig-box{{ disable : Int32 }, } 05-01 18:32:13.658 D/OMX-VENDOR-EXT( 3114): vendor.profile-level{{ n-profile : Int32 }, { n-level : Int32 }, } 05-01 18:32:13.658 D/OMX-VENDOR-EXT( 3114): vendor.nrdp{{ video-peek-in-tunnel : Int32 }, } 05-01 18:32:13.658 D/OMX-VENDOR-EXT( 3114): vendor.videoLayer{{ type : Int32 }, } 05-01 18:32:13.658 D/OmxComponentManagerImpl( 3114): IncreaseEntryNumByName pEntry->mNum=0, pEntry->mMaxNum:9,componentName:OMX.amlogic.avc.decoder.awesome 05-01 18:32:13.658 I/omx_core( 3114): OMX_getHandle_num=1 05-01 18:32:13.658 D/OmxComponent( 3114): mApkName=media.codec 05-01 18:32:13.659 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.661 V/MiniNDKMediaCodec(19784): private ctor 05-01 18:32:13.661 D/SurfaceUtils(19784): connecting to surface 0xa84fe008, reason connectToSurface 05-01 18:32:13.661 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.662 I/MediaCodec(19784): [OMX.amlogic.avc.decoder.awesome] setting surface generation to 20258817 05-01 18:32:13.662 D/SurfaceUtils(19784): disconnecting from surface 0xa84fe008, reason connectToSurface(reconnect) 05-01 18:32:13.663 D/SurfaceUtils(19784): connecting to surface 0xa84fe008, reason connectToSurface(reconnect) 05-01 18:32:13.663 I/AmAVUtils(19784): AmAVUtils::getComponentRole isEncoder :0 mime:video/avc 05-01 18:32:13.663 V/AVUtils (19784): AVUtils::getComponentRole 05-01 18:32:13.663 D/OmxVideoDecoder( 3114): setParameter 908 0x1000017 05-01 18:32:13.663 D/OmxVideoDecoder( 3114): setInputCoding video_decoder.avc 05-01 18:32:13.663 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.663 D/OmxComponent( 3114): getExtensionIndex 1122 name="OMX.google.android.index.enableAndroidNativeBuffers" 05-01 18:32:13.663 D/OmxVideoDecoder( 3114): setParameter 908 0x7f000000 05-01 18:32:13.664 V/OmxVideoDecoder( 3114): setParameter 1335 EnableNativeBuffers 1 05-01 18:32:13.664 V/OmxVideoDecoder( 3114): updatePort mUseNativeBuffers 1 05-01 18:32:13.664 D/AmlogicVideoDecoderAwesome( 3114): get native buffer info NV:w:640,h:480.. 05-01 18:32:13.664 V/OmxVideoDecoder( 3114): updatePort:portDef.format.video.nFrameWidth=640,portDef.format.video.nFrameHeight=480 05-01 18:32:13.664 D/OmxComponent( 3114): getExtensionIndex 1122 name="OMX.google.android.index.allocateNativeHandle" 05-01 18:32:13.664 D/OmxVideoDecoder( 3114): setParameter 908 0x7f00000a 05-01 18:32:13.664 E/OMXNodeInstance( 3114): setParameter(0xaa2040b8:amlogic.avc.decoder.awesome, OMX.google.android.index.allocateNativeHandle(0x7f00000a): Output:1 en=0) E RROR: UnsupportedSetting(0x80001019) 05-01 18:32:13.664 D/OmxComponent( 3114): getExtensionIndex 1122 name="OMX.google.android.index.storeANWBufferInMetadata" 05-01 18:32:13.664 D/OmxComponent( 3114): getExtensionIndex 1122 name="OMX.google.android.index.storeMetaDataInBuffers" 05-01 18:32:13.664 D/OmxVideoDecoder( 3114): setParameter 908 0x7f000002 05-01 18:32:13.664 D/OmxVideoDecoder( 3114): setParameter 1341 OMX_IndexAndroidStoreMetadataInBuffers 05-01 18:32:13.664 E/OMXNodeInstance( 3114): setParameter(0xaa2040b8:amlogic.avc.decoder.awesome, OMX.google.android.index.storeMetaDataInBuffers(0x7f000002): Output:1 en=1 type=1) ERROR: BadPortIndex(0x8000101b) 05-01 18:32:13.664 E/ACodec (19784): [OMX.amlogic.avc.decoder.awesome] setPortMode on output to DynamicANWBuffer failed w/ err -2147483648 05-01 18:32:13.664 D/OmxComponent( 3114): getExtensionIndex 1122 name="OMX.google.android.index.allocateNativeHandle" 05-01 18:32:13.664 D/OmxVideoDecoder( 3114): setParameter 908 0x7f00000a 05-01 18:32:13.664 E/OMXNodeInstance( 3114): setParameter(0xaa2040b8:amlogic.avc.decoder.awesome, OMX.google.android.index.allocateNativeHandle(0x7f00000a): Output:1 en=0) E RROR: UnsupportedSetting(0x80001019) 05-01 18:32:13.664 D/OmxComponent( 3114): getExtensionIndex 1122 name="OMX.google.android.index.enableAndroidNativeBuffers" 05-01 18:32:13.664 D/OmxVideoDecoder( 3114): setParameter 908 0x7f000000 05-01 18:32:13.664 V/OmxVideoDecoder( 3114): setParameter 1335 EnableNativeBuffers 1 05-01 18:32:13.664 V/OmxVideoDecoder( 3114): updatePort mUseNativeBuffers 1 05-01 18:32:13.664 D/AmlogicVideoDecoderAwesome( 3114): get native buffer info NV:w:640,h:480.. 05-01 18:32:13.665 V/OmxVideoDecoder( 3114): updatePort:portDef.format.video.nFrameWidth=640,portDef.format.video.nFrameHeight=480 05-01 18:32:13.665 D/OmxComponent( 3114): getExtensionIndex 1122 name="OMX.google.android.index.storeANWBufferInMetadata" 05-01 18:32:13.665 D/OmxComponent( 3114): getExtensionIndex 1122 name="OMX.google.android.index.storeMetaDataInBuffers" 05-01 18:32:13.665 D/OmxVideoDecoder( 3114): setParameter 908 0x7f000002 05-01 18:32:13.665 D/OmxVideoDecoder( 3114): setParameter 1341 OMX_IndexAndroidStoreMetadataInBuffers 05-01 18:32:13.665 E/OMXNodeInstance( 3114): setParameter(0xaa2040b8:amlogic.avc.decoder.awesome, OMX.google.android.index.storeMetaDataInBuffers(0x7f000002): Output:1 en=0 type=1) ERROR: BadPortIndex(0x8000101b) 05-01 18:32:13.665 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.665 D/OmxVideoDecoder( 3114): setParameter 908 0x6000001 05-01 18:32:13.665 D/OmxVideoDecoder( 3114): setParameter nPortIndex 0 CompFmt 0x7 ColorFmt 0 05-01 18:32:13.666 D/OmxVideoDecoder( 3114): setParameter 908 0x6000001 05-01 18:32:13.666 D/OmxVideoDecoder( 3114): setParameter nPortIndex 1 CompFmt 0 ColorFmt 0x11 05-01 18:32:13.667 D/OmxVideoDecoder( 3114): setParameter 908 0x2000001 05-01 18:32:13.667 D/OmxVideoDecoder( 3114): setParameter nPortIndex 0 nFrameWidth 1920 nFrameHeight 1080 nSliceHeight 16 05-01 18:32:13.667 V/OmxVideoDecoder( 3114): updatePort mUseNativeBuffers 1 05-01 18:32:13.667 D/AmlogicVideoDecoderAwesome( 3114): get native buffer info NV:w:640,h:480.. 05-01 18:32:13.667 V/OmxVideoDecoder( 3114): updatePort:portDef.format.video.nFrameWidth=640,portDef.format.video.nFrameHeight=480 05-01 18:32:13.667 D/OmxVideoDecoder( 3114): setParameter 908 0x2000001 05-01 18:32:13.667 D/OmxVideoDecoder( 3114): media.omx.display_mode=1 05-01 18:32:13.667 D/OmxVideoDecoder( 3114): mDecoderVideoDisplay = true, mDisplayMode=1, mVideoLayerType=0 05-01 18:32:13.667 D/OmxVideoDecoder( 3114): setParameter nPortIndex 1 nFrameWidth 1920 nFrameHeight 1080 nSliceHeight 1080 05-01 18:32:13.667 V/OmxVideoDecoder( 3114): updatePort mUseNativeBuffers 1 05-01 18:32:13.667 D/AmlogicVideoDecoderAwesome( 3114): get native buffer info NV:w:1920,h:1080.. 05-01 18:32:13.667 V/OmxVideoDecoder( 3114): updatePort:portDef.format.video.nFrameWidth=1920,portDef.format.video.nFrameHeight=1080 05-01 18:32:13.668 D/OmxComponent( 3114): getExtensionIndex 1122 name="OMX.google.android.index.describeColorAspects" 05-01 18:32:13.668 I/MiniDataSource(19784): first read, set read wait mode:READ_WAIT_LONG 05-01 18:32:13.668 I/MiniDataSource(19784): first read 32768 bytes, arrive at time:3673006971 05-01 18:32:13.668 D/OmxComponent( 3114): getExtensionIndex 1122 name="OMX.google.android.index.storeMetaDataInBuffers" 05-01 18:32:13.668 D/OmxComponent( 3114): getExtensionIndex 1122 name="OMX.google.android.index.storeANWBufferInMetadata" 05-01 18:32:13.668 D/OmxComponent( 3114): getExtensionIndex 1122 name="OMX.google.android.index.prepareForAdaptivePlayback" 05-01 18:32:13.668 D/OmxComponent( 3114): getExtensionIndex 1122 name="OMX.google.android.index.configureVideoTunnelMode" 05-01 18:32:13.668 D/OmxComponent( 3114): getExtensionIndex 1122 name="OMX.google.android.index.useAndroidNativeBuffer2" 05-01 18:32:13.668 D/OmxComponent( 3114): getExtensionIndex 1122 name="OMX.google.android.index.useAndroidNativeBuffer" 05-01 18:32:13.668 D/OmxComponent( 3114): getExtensionIndex 1122 name="OMX.google.android.index.enableAndroidNativeBuffers" 05-01 18:32:13.668 D/OmxComponent( 3114): getExtensionIndex 1122 name="OMX.google.android.index.allocateNativeHandle" 05-01 18:32:13.668 D/OmxComponent( 3114): getExtensionIndex 1122 name="OMX.google.android.index.getAndroidNativeBufferUsage" 05-01 18:32:13.668 D/OmxVideoDecoder( 3114): setConfig 1823 0x7f00000c 05-01 18:32:13.668 D/OmxVideoDecoder( 3114): [DSZ] updateFinalColorAspects# final color aspects[P:1; T:3; M:1; R:2] 05-01 18:32:13.669 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.669 D/OmxComponent( 3114): getExtensionIndex 1122 name="OMX.google.android.index.describeHDRStaticInfo" 05-01 18:32:13.669 D/OmxVideoDecoder( 3114): setConfig 1823 0x7f000009 05-01 18:32:13.669 D/OmxVideoDecoder( 3114): HDRStaticInfo: R=0 0; G=0 0; B=0 0; W=0 0; MaxDisplayLuminance:0, MinDisplayLuminance=0, mMaxContentLightLevel=0, MaxContentLigh tLevel=0 05-01 18:32:13.669 D/OmxVideoDecoder( 3114): HDRStaticInfo: all parmeter is 0, set mHasHDRStaticInfo to false 05-01 18:32:13.669 I/AmAVUtils(19784): AmAVUtils::isAudioExtendFormat input = video/avc 05-01 18:32:13.670 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.670 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=0 (4k-osd) 05-01 18:32:13.670 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.670 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=1 (is-mvc) 05-01 18:32:13.670 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.670 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=2 (is-flv) 05-01 18:32:13.671 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.671 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=3 (dvconfig-box) 05-01 18:32:13.672 I/StreamFormatter(19784): Creating formatter for codec id : 28 extradata size : 0 05-01 18:32:13.672 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.672 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=4 (profile-level) 05-01 18:32:13.672 D/StreamFormatter(19784): [Create:93] 05-01 18:32:13.672 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.672 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=4 (profile-level) 05-01 18:32:13.672 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.672 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=5 (nrdp) 05-01 18:32:13.672 I/StreamFormatter(19784): Creating formatter for codec id : 86018 extradata size : 0 05-01 18:32:13.672 D/StreamFormatter(19784): [Create:73] 05-01 18:32:13.672 I/AACFormatter(19784): [AACFormatter:33] 05-01 18:32:13.672 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.672 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=6 (videoLayer) 05-01 18:32:13.672 D/OmxVideoDecoder( 3114): setConfig 1823 0x6f100004 05-01 18:32:13.672 E/OmxVideoDecoder( 3114): setConfig 1904 OMX_IndexConfigAndroidVendorExtension, nIndex 0x6f100004, nIndex:6, cName:videoLayer, nParamCount:1 05-01 18:32:13.672 I/OmxVideoDecoder( 3114): set_vendor_extension_config 05-01 18:32:13.672 V/OmxVideoDecoder( 3114): VendorExt: setConfig: index=6 (videoLayer) 05-01 18:32:13.672 I/OMX-VENDOR-EXT( 3114): isConfigValid 05-01 18:32:13.672 V/OmxVideoDecoder( 3114): VendorExt: setConfig: vExt.extensionIndex()=2130706706, OMX_IndexParamVideoLayerType=2130706706 05-01 18:32:13.672 D/OmxVideoDecoder( 3114): VENDOR-EXT: set_config: OMX_IndexParamVideoLayerType : 1, valueSet:1 05-01 18:32:13.672 D/OmxVideoDecoder( 3114): VENDOR-EXT: set_config: OMX_IndexParamVideoLayerType : 1 05-01 18:32:13.673 D/OmxVideoDecoder( 3114): setParameter 908 0x7f000112 05-01 18:32:13.673 D/OmxVideoDecoder( 3114): OMX_IndexParamVideoLayerType: before IsValidOmxType 05-01 18:32:13.673 D/OmxVideoDecoder( 3114): OMX_IndexParamVideoLayerType: nVideoLayerType:1 05-01 18:32:13.673 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.675 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.675 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=0 (4k-osd) 05-01 18:32:13.675 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.675 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=1 (is-mvc) 05-01 18:32:13.675 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.675 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=2 (is-flv) 05-01 18:32:13.675 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.675 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=3 (dvconfig-box) 05-01 18:32:13.675 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.675 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=4 (profile-level) 05-01 18:32:13.675 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.676 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.676 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=4 (profile-level) 05-01 18:32:13.676 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.676 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=5 (nrdp) 05-01 18:32:13.676 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.676 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=6 (videoLayer) 05-01 18:32:13.676 D/Chronicle_0(19784): chronicle: first bytes arrive at: 3673006971, 106.9 ms from birth, 59.7 ms from parse start 05-01 18:32:13.676 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.676 D/Chronicle_0(19784): chronicle: parsing end at: 3673015481, 107.4 ms from birth, 68.2 ms from parse start, 8.5 ms from first byte received 05-01 18:32:13.677 V/OmxVideoDecoder( 3114): updatePort mUseNativeBuffers 1 05-01 18:32:13.677 D/AmlogicVideoDecoderAwesome( 3114): get native buffer info NV:w:1920,h:1080.. 05-01 18:32:13.677 V/OmxVideoDecoder( 3114): updatePort:portDef.format.video.nFrameWidth=1920,portDef.format.video.nFrameHeight=1080 05-01 18:32:13.677 D/OmxVideoDecoder( 3114): getConfig 1721 0x700000f 05-01 18:32:13.677 D/OmxVideoDecoder( 3114): getConfig 1724 nIndex 0x700000f 05-01 18:32:13.677 D/OmxVideoDecoder( 3114): getConfig OMX_IndexConfigCommonOutputCrop pRect->nWidth=1920, pRect->nHeight=1080 05-01 18:32:13.677 D/OmxVideoDecoder( 3114): setConfig 1823 0x7f00000c 05-01 18:32:13.677 D/OmxVideoDecoder( 3114): getConfig 1721 0x7f00000c 05-01 18:32:13.677 D/OmxVideoDecoder( 3114): getConfig 1721 0x7f00000c 05-01 18:32:13.677 E/OMXNodeInstance( 3114): getConfig(0xaa2040b8:amlogic.avc.decoder.awesome, ??(0x7f00000c)) ERROR: UnsupportedSetting(0x80001019) 05-01 18:32:13.677 D/OmxVideoDecoder( 3114): getConfig 1721 0x7f000009 05-01 18:32:13.677 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.677 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=0 (4k-osd) 05-01 18:32:13.678 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.678 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=1 (is-mvc) 05-01 18:32:13.678 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.678 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=2 (is-flv) 05-01 18:32:13.678 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.678 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=3 (dvconfig-box) 05-01 18:32:13.678 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.678 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=4 (profile-level) 05-01 18:32:13.678 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.678 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=4 (profile-level) 05-01 18:32:13.678 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.678 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=5 (nrdp) 05-01 18:32:13.679 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.679 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=6 (videoLayer) 05-01 18:32:13.679 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.680 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.681 I/OmxComponent( 3114): OMX_CommandStateSet 739 Cmd 0 nParam1 0x2 05-01 18:32:13.681 I/OmxComponent( 3114): STATE_SET: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.avc.decoder.awesome 05-01 18:32:13.681 I/OmxComponent( 3114): STATE_TRANS: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.avc.decoder.awesome 05-01 18:32:13.687 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.688 I/OmxComponent( 3114): STATE_TRANS: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.avc.decoder.awesome 05-01 18:32:13.689 I/OmxComponent( 3114): STATE_TRANS: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.avc.decoder.awesome 05-01 18:32:13.690 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.690 I/OmxComponent( 3114): STATE_TRANS: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.avc.decoder.awesome 05-01 18:32:13.690 V/OmxVideoDecoder( 3114): updatePort mUseNativeBuffers 1 05-01 18:32:13.690 D/AmlogicVideoDecoderAwesome( 3114): get native buffer info NV:w:1920,h:1080.. 05-01 18:32:13.690 V/OmxVideoDecoder( 3114): updatePort:portDef.format.video.nFrameWidth=1920,portDef.format.video.nFrameHeight=1080 05-01 18:32:13.690 V/OmxVideoDecoder( 3114): updatePort mUseNativeBuffers 1 05-01 18:32:13.690 D/AmlogicVideoDecoderAwesome( 3114): get native buffer info NV:w:1920,h:1080.. 05-01 18:32:13.690 V/OmxVideoDecoder( 3114): updatePort:portDef.format.video.nFrameWidth=1920,portDef.format.video.nFrameHeight=1080 05-01 18:32:13.690 D/OmxComponent( 3114): getExtensionIndex 1122 name="OMX.google.android.index.AndroidNativeBufferConsumerUsage" 05-01 18:32:13.691 D/OmxVideoDecoder( 3114): setParameter 908 0x7f00000b 05-01 18:32:13.691 D/OmxVideoDecoder( 3114): OMX_GoogleAndroidIndexAndroidNativeBufferConsumerUsage 0x900 05-01 18:32:13.691 D/OmxComponent( 3114): getExtensionIndex 1122 name="OMX.google.android.index.getAndroidNativeBufferUsage" 05-01 18:32:13.691 D/SurfaceUtils(19784): disconnecting from surface 0xa84fe008, reason setNativeWindowSizeFormatAndUsage 05-01 18:32:13.691 D/SurfaceUtils(19784): connecting to surface 0xa84fe008, reason setNativeWindowSizeFormatAndUsage 05-01 18:32:13.691 D/SurfaceUtils(19784): set up nativeWindow 0xa84fe008 for 1920x1080, color 0x11, rotation 0, usage 0x402933 05-01 18:32:13.692 D/OmxVideoDecoder( 3114): setParameter 908 0x2000001 05-01 18:32:13.692 D/OmxVideoDecoder( 3114): media.omx.display_mode=1 05-01 18:32:13.692 D/OmxVideoDecoder( 3114): mAmVideoUsed:0, mPipVideoUsed:0 05-01 18:32:13.692 D/OmxVideoDecoder( 3114): mDecoderVideoDisplay = true, mDisplayMode=1, mVideoLayerType=1 05-01 18:32:13.692 D/OmxVideoDecoder( 3114): setParameter nPortIndex 1 nFrameWidth 1920 nFrameHeight 1080 nSliceHeight 1080 05-01 18:32:13.692 V/OmxVideoDecoder( 3114): updatePort mUseNativeBuffers 1 05-01 18:32:13.692 D/AmlogicVideoDecoderAwesome( 3114): get native buffer info NV:w:1920,h:1080.. 05-01 18:32:13.692 V/OmxVideoDecoder( 3114): updatePort:portDef.format.video.nFrameWidth=1920,portDef.format.video.nFrameHeight=1080 05-01 18:32:13.692 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.693 W/ ( 3030): buffer descriptor with invalid usage bits 0x2000 05-01 18:32:13.693 I/[Gralloc]( 3007): framebuffer hal alread move to hwcomposer 05-01 18:32:13.694 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.696 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.701 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.704 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.709 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.711 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.713 D/OmxComponent( 3114): getExtensionIndex 1122 name="OMX.google.android.index.useAndroidNativeBuffer2" 05-01 18:32:13.713 V/OmxVideoDecoder( 3114): updatePort mUseNativeBuffers 1 05-01 18:32:13.713 D/AmlogicVideoDecoderAwesome( 3114): get native buffer info NV:w:1920,h:1080.. 05-01 18:32:13.713 V/OmxVideoDecoder( 3114): updatePort:portDef.format.video.nFrameWidth=1920,portDef.format.video.nFrameHeight=1080 05-01 18:32:13.713 I/OmxComponent( 3114): STATE_TRANS: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.avc.decoder.awesome 05-01 18:32:13.714 W/ ( 3030): buffer descriptor with invalid usage bits 0x2000 05-01 18:32:13.714 I/[Gralloc]( 3007): framebuffer hal alread move to hwcomposer 05-01 18:32:13.715 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.715 I/MiniDataSource(19784): we_received 131072 bytes data 05-01 18:32:13.717 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.718 D/OmxComponent( 3114): getExtensionIndex 1122 name="OMX.google.android.index.useAndroidNativeBuffer2" 05-01 18:32:13.718 V/OmxVideoDecoder( 3114): updatePort mUseNativeBuffers 1 05-01 18:32:13.718 D/AmlogicVideoDecoderAwesome( 3114): get native buffer info NV:w:1920,h:1080.. 05-01 18:32:13.718 V/OmxVideoDecoder( 3114): updatePort:portDef.format.video.nFrameWidth=1920,portDef.format.video.nFrameHeight=1080 05-01 18:32:13.718 I/OmxComponent( 3114): STATE_TRANS: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.avc.decoder.awesome 05-01 18:32:13.718 W/ ( 3030): buffer descriptor with invalid usage bits 0x2000 05-01 18:32:13.718 I/[Gralloc]( 3007): framebuffer hal alread move to hwcomposer 05-01 18:32:13.718 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.721 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.723 D/OmxComponent( 3114): getExtensionIndex 1122 name="OMX.google.android.index.useAndroidNativeBuffer2" 05-01 18:32:13.723 V/OmxVideoDecoder( 3114): updatePort mUseNativeBuffers 1 05-01 18:32:13.723 D/AmlogicVideoDecoderAwesome( 3114): get native buffer info NV:w:1920,h:1080.. 05-01 18:32:13.723 V/OmxVideoDecoder( 3114): updatePort:portDef.format.video.nFrameWidth=1920,portDef.format.video.nFrameHeight=1080 05-01 18:32:13.723 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.723 I/OmxComponent( 3114): STATE_TRANS: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.avc.decoder.awesome 05-01 18:32:13.723 W/ ( 3030): buffer descriptor with invalid usage bits 0x2000 05-01 18:32:13.723 I/[Gralloc]( 3007): framebuffer hal alread move to hwcomposer 05-01 18:32:13.725 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.726 D/OmxComponent( 3114): getExtensionIndex 1122 name="OMX.google.android.index.useAndroidNativeBuffer2" 05-01 18:32:13.726 V/OmxVideoDecoder( 3114): updatePort mUseNativeBuffers 1 05-01 18:32:13.726 D/AmlogicVideoDecoderAwesome( 3114): get native buffer info NV:w:1920,h:1080.. 05-01 18:32:13.726 V/OmxVideoDecoder( 3114): updatePort:portDef.format.video.nFrameWidth=1920,portDef.format.video.nFrameHeight=1080 05-01 18:32:13.726 I/OmxComponent( 3114): STATE_TRANS: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.avc.decoder.awesome 05-01 18:32:13.726 W/ ( 3030): buffer descriptor with invalid usage bits 0x2000 05-01 18:32:13.726 I/[Gralloc]( 3007): framebuffer hal alread move to hwcomposer 05-01 18:32:13.727 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.729 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.729 D/OmxComponent( 3114): getExtensionIndex 1122 name="OMX.google.android.index.useAndroidNativeBuffer2" 05-01 18:32:13.729 V/OmxVideoDecoder( 3114): updatePort mUseNativeBuffers 1 05-01 18:32:13.729 D/AmlogicVideoDecoderAwesome( 3114): get native buffer info NV:w:1920,h:1080.. 05-01 18:32:13.730 V/OmxVideoDecoder( 3114): updatePort:portDef.format.video.nFrameWidth=1920,portDef.format.video.nFrameHeight=1080 05-01 18:32:13.730 I/OmxComponent( 3114): STATE_TRANS: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.avc.decoder.awesome 05-01 18:32:13.730 W/ ( 3030): buffer descriptor with invalid usage bits 0x2000 05-01 18:32:13.730 I/[Gralloc]( 3007): framebuffer hal alread move to hwcomposer 05-01 18:32:13.731 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.731 I/MiniDataSource(19784): we_received 229376 bytes data 05-01 18:32:13.735 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.735 D/OmxComponent( 3114): getExtensionIndex 1122 name="OMX.google.android.index.useAndroidNativeBuffer2" 05-01 18:32:13.735 V/OmxVideoDecoder( 3114): updatePort mUseNativeBuffers 1 05-01 18:32:13.735 D/AmlogicVideoDecoderAwesome( 3114): get native buffer info NV:w:1920,h:1080.. 05-01 18:32:13.735 V/OmxVideoDecoder( 3114): updatePort:portDef.format.video.nFrameWidth=1920,portDef.format.video.nFrameHeight=1080 05-01 18:32:13.735 I/OmxComponent( 3114): STATE_TRANS: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.avc.decoder.awesome 05-01 18:32:13.735 W/ ( 3030): buffer descriptor with invalid usage bits 0x2000 05-01 18:32:13.736 I/[Gralloc]( 3007): framebuffer hal alread move to hwcomposer 05-01 18:32:13.739 D/OmxComponent( 3114): getExtensionIndex 1122 name="OMX.google.android.index.useAndroidNativeBuffer2" 05-01 18:32:13.739 V/OmxVideoDecoder( 3114): updatePort mUseNativeBuffers 1 05-01 18:32:13.739 D/AmlogicVideoDecoderAwesome( 3114): get native buffer info NV:w:1920,h:1080.. 05-01 18:32:13.739 V/OmxVideoDecoder( 3114): updatePort:portDef.format.video.nFrameWidth=1920,portDef.format.video.nFrameHeight=1080 05-01 18:32:13.739 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.739 I/OmxComponent( 3114): STATE_TRANS: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.avc.decoder.awesome 05-01 18:32:13.740 W/ ( 3030): buffer descriptor with invalid usage bits 0x2000 05-01 18:32:13.740 I/[Gralloc]( 3007): framebuffer hal alread move to hwcomposer 05-01 18:32:13.741 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.744 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.745 D/OmxComponent( 3114): getExtensionIndex 1122 name="OMX.google.android.index.useAndroidNativeBuffer2" 05-01 18:32:13.745 V/OmxVideoDecoder( 3114): updatePort mUseNativeBuffers 1 05-01 18:32:13.745 D/AmlogicVideoDecoderAwesome( 3114): get native buffer info NV:w:1920,h:1080.. 05-01 18:32:13.745 V/OmxVideoDecoder( 3114): updatePort:portDef.format.video.nFrameWidth=1920,portDef.format.video.nFrameHeight=1080 05-01 18:32:13.746 I/OmxComponent( 3114): STATE_TRANS: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.avc.decoder.awesome 05-01 18:32:13.746 V/AmlogicVideoDecoderAwesome( 3114): acquireResources:360 > 05-01 18:32:13.746 D/AmlogicVideoDecoderAwesome( 3114): setUp mVideoDisplay =1, mDisplayMode=1 05-01 18:32:13.746 D/AmlogicVideoDecoderAwesome( 3114): mMaxFrameWidth 1920, mMaxFrameHeight 1080 05-01 18:32:13.746 I/AmlogicVideoDecoderAwesome( 3114): video layer mKeepLastFrame 1 05-01 18:32:13.746 D/AmlogicVideoDecoderAwesome( 3114): video_display_mode=2 05-01 18:32:13.746 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.746 V/AmlogicVideoDecoderAwesome( 3114): enable osd or video ...0 05-01 18:32:13.746 V/AmlogicVideoDecoderAwesome( 3114): media.omx.buffermargin is not set used def=0 05-01 18:32:13.746 D/AmlogicVideoDecoderAwesome( 3114): mDoubleWriteMode 0 05-01 18:32:13.746 V/AmlogicVideoDecoderAwesome( 3114): media.omx.dw is not set used def=0 05-01 18:32:13.746 D/AmlogicVideoDecoderAwesome( 3114): AmlogicVideoDecoder::setUp mNeedCheckDtv = 1 05-01 18:32:13.747 I/MiniDataSource(19784): we_received 327680 bytes data 05-01 18:32:13.748 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.746 V/debugSetup( 3114): media.omx.dumpRecv is disabled 05-01 18:32:13.748 V/debugSetup( 3114): media.omx.dumpCodec is disabled 05-01 18:32:13.748 V/AmlogicVideoDecoderAwesome( 3114): Input Frame Rate is 0.. 05-01 18:32:13.748 V/AmlogicVideoDecoderAwesome( 3114): videoDefine.xFramerate:0 05-01 18:32:13.748 V/AmlogicVideoDecoderAwesome( 3114): mVideoType:7 05-01 18:32:13.748 D/AmlogicVideoDecoderAwesome( 3114): decoder_type = 1 05-01 18:32:13.748 V/AmlogicVideoDecoderAwesome( 3114): set FRAME_BASE_USE_VFM_PATH =1 05-01 18:32:13.748 D/AmlogicVideoDecoderAwesome( 3114): AmlogicVideoDecoder::setUp, mUseFrameMode 1, video_path 1 05-01 18:32:13.748 D/AmlogicVideoDecoderAwesome( 3114): mh264 profile support double write 05-01 18:32:13.748 D/AmlogicVideoDecoderAwesome( 3114): mAmlCodecParam->config=mh264_double_write_mode:0; 05-01 18:32:13.749 D/AmlogicVideoDecoderAwesome( 3114): mAmlCodecParam->config_len=27 05-01 18:32:13.749 D/AmlogicVideoDecoderAwesome( 3114): codecInit 05-01 18:32:13.750 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.754 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.755 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.757 I/MiniDataSource(19784): we_received 425984 bytes data 05-01 18:32:13.758 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.762 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.765 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.767 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.767 I/MiniDataSource(19784): we_received 524288 bytes data 05-01 18:32:13.769 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.771 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.774 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.776 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.778 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.779 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.781 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.783 I/MiniDataSource(19784): we_received 622592 bytes data 05-01 18:32:13.783 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.785 D/AmlogicVideoDecoderAwesome( 3114): codecInit done 05-01 18:32:13.785 D/AmlogicVideoDecoderAwesome( 3114): media.omx.videopeek 0 05-01 18:32:13.785 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.785 I/AmlogicVideoDecoderAwesome( 3114): video layer sync version is 3 05-01 18:32:13.785 V/AmlogicVideoDecoderAwesome( 3114): mFlvFlag=0 05-01 18:32:13.785 V/AmlogicVideoDecoderAwesome( 3114): mOutBufferCount =8 05-01 18:32:13.785 V/AmlogicVideoDecoderAwesome( 3114): mIsNativeBuffers =1 05-01 18:32:13.785 D/AmlogicVideoDecoderAwesome( 3114): mAmvideoDev->use_frame_mode=0 05-01 18:32:13.786 D/AmlogicVideoDecoderAwesome( 3114): mAmvideoDev->use_frame_mode=1, mUseFrameMode=1 05-01 18:32:13.786 I/amvdec ( 3114): amvideo_init ret=0 05-01 18:32:13.786 D/AmlogicVideoDecoderAwesome( 3114): output buffer:mWidth:1920, mHeight:1080, mVideoDevId:0 05-01 18:32:13.787 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.789 I/OmxComponent( 3114): STATE_DONE: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.avc.decoder.awesome 05-01 18:32:13.789 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.790 I/OmxComponent( 3114): OMX_CommandStateSet 739 Cmd 0 nParam1 0x3 05-01 18:32:13.790 I/OmxComponent( 3114): STATE_SET: OMX_StateIdle => OMX_StateExecuting : OMX.amlogic.avc.decoder.awesome 05-01 18:32:13.790 V/AmlogicVideoDecoderAwesome( 3114): prepare:281 > 05-01 18:32:13.790 D/AmlogicVideoDecoderAwesome( 3114): mVideo mDisplayTread start 05-01 18:32:13.790 D/AmlogicVideoDecoderAwesome( 3114): mDTaskStatus=0 05-01 18:32:13.790 V/AmlogicVideoDecoderAwesome( 3114): start:294 > 05-01 18:32:13.790 D/AmlogicVideoDecoderAwesome( 3114): mVideo start 05-01 18:32:13.791 I/OmxComponent( 3114): STATE_TRANS: OMX_StateIdle => OMX_StateExecuting : OMX.amlogic.avc.decoder.awesome 05-01 18:32:13.791 I/OmxComponent( 3114): STATE_DONE: OMX_StateIdle => OMX_StateExecuting : OMX.amlogic.avc.decoder.awesome 05-01 18:32:13.791 V/OMX_WorkerPeer( 3114): vendor/omx/omx_framework/WorkerPeer.cpp:runWorkerStatic:152 -------------------- 05-01 18:32:13.791 V/OMX_WorkerPeer( 3114): vendor/omx/omx_framework/WorkerPeer.cpp:runWorker:158 -------------------- 05-01 18:32:13.791 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.792 D/AmlogicVideoDecoderAwesome( 3114): memset outputbuffer index=0 05-01 18:32:13.793 D/AmlogicVideoDecoderAwesome( 3114): memset outputbuffer index=1 05-01 18:32:13.793 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.793 D/AmlogicVideoDecoderAwesome( 3114): memset outputbuffer index=2 05-01 18:32:13.794 D/AmlogicVideoDecoderAwesome( 3114): memset outputbuffer index=3 05-01 18:32:13.794 I/MiniDataSource(19784): we_received 720896 bytes data 05-01 18:32:13.795 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.796 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.797 I/MiniCCGetESData(19784): miniCCGetESDataOpen: mime:video/avc, para.vfmt=2, mUdDevNo=0 05-01 18:32:13.797 I/am_cc_in(19784): cc thread, pthred_self:-1572341392, ud:0xa940e620 05-01 18:32:13.797 I/MiniCCGetESData(19784): miniCCGetESDataOpen: open success 05-01 18:32:13.798 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.800 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.801 D/Chronicle_0(19784): chronicle: first I frame arrive at: 3673140070, pts:2033333, 232.0 ms from birth, 124.6 ms from parse end, 133.1 ms from first byte received 05-01 18:32:13.802 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.808 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.809 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.812 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.815 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.817 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.818 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.823 D/AmlogicVideoDecoderAwesome( 3114): =7, =1920, =1080, =0, =0, =2033333, =2066700, =2100066, =14851, =14365, =20710 05-01 18:32:13.824 I/MiniDataSource(19784): we_received 819200 bytes data 05-01 18:32:13.824 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.826 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.830 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.831 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.833 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.838 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.841 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.845 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.845 I/MiniDataSource(19784): we_received 917504 bytes data 05-01 18:32:13.846 D/AmlogicVideoDecoderAwesome( 3114): mFirstInputTime = 3673141, mFirstOutputTime=3673185, delay= 44ms 05-01 18:32:13.847 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.851 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.852 D/SystemControl( 2996): [CPQControl]:sig_fmt = 1034 05-01 18:32:13.852 D/SystemControl( 2996): [CPQControl]:source_input: 10 05-01 18:32:13.853 D/SystemControl( 2996): [CPQControl]:sig_fmt: 0x40a(1034) 05-01 18:32:13.853 D/SystemControl( 2996): [CPQControl]:trans_fmt: 0x0 05-01 18:32:13.853 D/SystemControl( 2996): [CPQControl]:GetHDRMode: mode is 32 05-01 18:32:13.853 D/SystemControl( 2996): [CPQControl]:Same signal,no need load! 05-01 18:32:13.854 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.856 V/OmxVideoDecoder( 3114): updatePort mUseNativeBuffers 1 05-01 18:32:13.856 D/AmlogicVideoDecoderAwesome( 3114): get native buffer info NV:w:1920,h:1080.. 05-01 18:32:13.856 V/OmxVideoDecoder( 3114): updatePort:portDef.format.video.nFrameWidth=1920,portDef.format.video.nFrameHeight=1080 05-01 18:32:13.856 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.856 D/OmxVideoDecoder( 3114): getConfig 1721 0x700000f 05-01 18:32:13.856 D/OmxVideoDecoder( 3114): getConfig 1724 nIndex 0x700000f 05-01 18:32:13.856 D/OmxVideoDecoder( 3114): getConfig OMX_IndexConfigCommonOutputCrop pRect->nWidth=1920, pRect->nHeight=1080 05-01 18:32:13.857 D/OmxVideoDecoder( 3114): setConfig 1823 0x7f00000c 05-01 18:32:13.857 D/OmxVideoDecoder( 3114): getConfig 1721 0x7f00000c 05-01 18:32:13.857 D/OmxVideoDecoder( 3114): getConfig 1721 0x7f00000c 05-01 18:32:13.857 E/OMXNodeInstance( 3114): getConfig(0xaa2040b8:amlogic.avc.decoder.awesome, ??(0x7f00000c)) ERROR: UnsupportedSetting(0x80001019) 05-01 18:32:13.858 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.859 D/OmxVideoDecoder( 3114): getConfig 1721 0x7f000009 05-01 18:32:13.859 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.859 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=0 (4k-osd) 05-01 18:32:13.859 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.859 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=1 (is-mvc) 05-01 18:32:13.859 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.859 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=2 (is-flv) 05-01 18:32:13.860 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.860 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=3 (dvconfig-box) 05-01 18:32:13.860 I/AACFormatter(19784): found adts header! 05-01 18:32:13.860 I/AACFormatter(19784): profile:1, sampling_freq_index:3, channel_configuration:2 05-01 18:32:13.860 I/AACFormatter(19784): csd aac:0x11 0x90 05-01 18:32:13.860 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.860 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=4 (profile-level) 05-01 18:32:13.860 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.860 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=4 (profile-level) 05-01 18:32:13.860 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.860 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=5 (nrdp) 05-01 18:32:13.860 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.860 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.860 V/OmxVideoDecoder( 3114): VendorExt: getConfig: index=6 (videoLayer) 05-01 18:32:13.860 D/OmxVideoDecoder( 3114): getConfig 1721 0x6f100004 05-01 18:32:13.862 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.865 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.867 I/SoftAAC2( 3114): Reconfiguring decoder: 0->48000 Hz, 0->2 channels 05-01 18:32:13.867 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.872 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.873 D/Chronicle_0(19784): chronicle: first video decoded at: 3673211624, pts:2033333, 303.6 ms from birth, 196.1 ms from parse end, 71.6 ms from first I fram e 05-01 18:32:13.874 D/AmlHalPlayer(19784): onAMLEvent() >> begin 05-01 18:32:13.874 D/AmlHalPlayer(19784): onAmlEvent() >> begin 05-01 18:32:13.874 I/AmlHalPlayer(19784): onAmlEvent: EVENT_FIRST_IFRAME_DISPLAYED 05-01 18:32:13.874 D/AmlHalPlayer(19784): onAmlEvent() << end 0 ms 05-01 18:32:13.874 D/AmlHalPlayer(19784): onAMLEvent() << end 0 ms 05-01 18:32:13.874 D/Chronicle_0(19784): chronicle: first video rendered at: 3673213016, 305.0 ms from birth, 197.5 ms from parse end 05-01 18:32:13.874 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.874 I/omxutil ( 3030): open amvideo handle 0x2d 05-01 18:32:13.875 D/AmlogicVideoDecoderAwesome( 3114): memset outputbuffer index=4 05-01 18:32:13.876 D/HalPlayer(19784): update() >> begin 05-01 18:32:13.876 D/HalPlayer(19784): No need to update stc 05-01 18:32:13.876 D/HalPlayer(19784): audio need to change: track count, but set again because of multi audio decoder in SKB 05-01 18:32:13.876 I/HalPlayer(19784): Need to change AUDIO 05-01 18:32:13.876 D/AmlAudio(19784): change() >> begin 05-01 18:32:13.876 I/AmlAudio(19784): ======================================================================================== 05-01 18:32:13.876 I/AmlAudio(19784): 05-01 18:32:13.876 I/AmlAudio(19784): dumpAudioInfo: count:1, mute:0, selectedTrackIndex=0 05-01 18:32:13.876 I/AmlAudio(19784): 05-01 18:32:13.876 I/AmlAudio(19784): [ i=0 ] decode=1, codec=0xf, pid=0x1e2 05-01 18:32:13.876 I/AmlAudio(19784): audioFocusedStart:0, trackChanged=0, zorder=0 05-01 18:32:13.876 I/AmlAudio(19784): isAudioOnlyChannel:0, isComposerDataStarted=0 05-01 18:32:13.876 I/AmlAudio(19784): 05-01 18:32:13.876 I/AmlAudio(19784): ======================================================================================== 05-01 18:32:13.876 D/AmlAudio(19784): mConfig.trackCount:1, mConfig.selectedTrackIndex:0 05-01 18:32:13.876 D/AmlAudio(19784): Audio track Selection is SAME 05-01 18:32:13.876 D/AmlAudio(19784): doStart() >> begin 05-01 18:32:13.876 D/AmlAudio(19784): doStart() << end 1 ms 05-01 18:32:13.876 D/AmlAudio(19784): setMute() >> begin 05-01 18:32:13.876 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.876 D/AmlAudio(19784): setMute() << end 0 ms 05-01 18:32:13.876 D/AmlAudio(19784): change() << end 1 ms 05-01 18:32:13.876 D/HalPlayer(19784): No need to update video 05-01 18:32:13.876 D/HalPlayer(19784): update() << end 1 ms 05-01 18:32:13.876 I/ZapTimeLogger(19784): ==================================================================== 05-01 18:32:13.876 I/ZapTimeLogger(19784): Zapping time 05-01 18:32:13.876 I/ZapTimeLogger(19784): -------------------------------------------------------------------- 05-01 18:32:13.876 I/ZapTimeLogger(19784): 0ms ~ 32ms ( 32ms) : create new player 05-01 18:32:13.876 I/ZapTimeLogger(19784): 52ms : start new player 05-01 18:32:13.876 I/ZapTimeLogger(19784): 63ms : pat received 05-01 18:32:13.876 I/ZapTimeLogger(19784): 63ms : pmt received 05-01 18:32:13.876 I/ZapTimeLogger(19784): 330ms : first iframe displayed 05-01 18:32:13.876 I/ZapTimeLogger(19784): ==================================================================== 05-01 18:32:13.876 D/TVSMMFEvent(19784): type=2, what=10000, extra=20008 05-01 18:32:13.876 I/TVSMMFEvent(19784): event: source (1), type(INFO), what(10000), extra(20008) 05-01 18:32:13.877 D/TVSGtvPlayer( 3097): onPlayerEvent() >> begin 05-01 18:32:13.877 D/TVSGtvPlayer( 3097): event : type(2), what(10000), extra(20008) 05-01 18:32:13.877 D/TVSGtvPlayer( 3097): notify INFO : 10000, 20008 05-01 18:32:13.877 D/TVSGtvPlayer( 3097): sendEvent() >> begin 05-01 18:32:13.877 D/TVSGtvPlayer( 3097): onSendEvent() >> begin 05-01 18:32:13.877 D/TVSGtvPlayer( 3097): onSendEvent() << end 0 ms 05-01 18:32:13.877 D/TVSGtvPlayer( 3097): sendEvent() << end 0 ms 05-01 18:32:13.877 D/TVSGtvPlayer( 3097): onPlayerEvent() << end 0 ms 05-01 18:32:13.877 W/MediaPlayerNative( 8541): info/warning (10000, 20008) 05-01 18:32:13.877 I/BtvMediaPlayer( 8541): onInfo what: 10000, extra: 20008 05-01 18:32:13.878 D/TVSMediaServerImpl(19784): onNotifyMediaServerEvent() >> begin 05-01 18:32:13.878 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.878 D/TVSMediaServerImpl(19784): onNotifyMediaServerEvent() << end 0 ms 05-01 18:32:13.878 I/TVSMMFClient( 4081): ===== tvs_mediaserver event callback: 0xad40a52c 05-01 18:32:13.879 D/ChannelMediaEventManager( 4081): Event : 10000, Source : 1, URI : skbfile:///data/skb_data/DATA/adv/cache/O0000001033_190425190526.ts 05-01 18:32:13.879 D/DatacastingManager( 4081): [371] Event Type : 10000, Source : 1, Event Data(URI) : skbfile:///data/skb_data/DATA/adv/cache/O0000001033_190425190526.ts 05-01 18:32:13.879 I/TVSMMFClient( 4020): ===== tvs_mediaserver event callback: 0xa35b6e3c 05-01 18:32:13.879 I/JNI_Settings( 4020): tvsMediaCallback(10000) 05-01 18:32:13.879 I/JNI_Settings( 4020): what(10000), extra(20008), data size(67) 05-01 18:32:13.879 D/MediaEventHandler( 4081): [114] Event Type : 10000, Source : 1, Event Data(URI) : skbfile:///data/skb_data/DATA/adv/cache/O0000001033_190425190526.ts 05-01 18:32:13.879 D/MediaEventHandler( 4081): [114] Ignore Event Type : 10000 05-01 18:32:13.879 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.881 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.883 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.886 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.890 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.892 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.899 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.901 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.904 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.906 D/MiniAudioSink(19784): mSampleSize:2, mChannelCount:2, mSampleRate:48000 05-01 18:32:13.906 D/AudioOutput(19784): [AudioOutput:17] flags:0x2 05-01 18:32:13.906 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.908 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.909 I/MiniAudioSink(19784): setmute:0 05-01 18:32:13.910 D/Chronicle_0(19784): chronicle: first audio decoded at: 3673249117, pts:2042666, 341.1 ms from birth, 233.6 ms from parse end 05-01 18:32:13.910 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.911 D/AmlHalPlayer(19784): onAMLEvent() >> begin 05-01 18:32:13.911 D/Chronicle_0(19784): chronicle: first audio rendered at: 3673249523, 341.5 ms from birth, 234.0 ms from parse end 05-01 18:32:13.911 D/AmlHalPlayer(19784): onAmlEvent() >> begin 05-01 18:32:13.911 I/AmlHalPlayer(19784): onAmlEvent: EVENT_AV_SYNC_LOCKED 05-01 18:32:13.911 D/AmlHalPlayer(19784): onAmlEvent() << end 0 ms 05-01 18:32:13.911 D/AmlHalPlayer(19784): onAMLEvent() << end 0 ms 05-01 18:32:13.913 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.916 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.917 I/MiniDataSource(19784): we_received 1015808 bytes data 05-01 18:32:13.918 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.920 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.922 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.927 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.929 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.936 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.935 D/AmlogicVideoDecoderAwesome( 3114): memset outputbuffer index=5 05-01 18:32:13.937 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.939 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.941 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.951 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.953 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.958 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.962 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.964 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.967 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.968 D/SystemControl( 2996): [CPQControl]:sig_fmt = 1034 05-01 18:32:13.968 D/SystemControl( 2996): [CPQControl]:source_input: 10 05-01 18:32:13.968 D/SystemControl( 2996): [CPQControl]:sig_fmt: 0x40a(1034) 05-01 18:32:13.968 D/SystemControl( 2996): [CPQControl]:trans_fmt: 0x0 05-01 18:32:13.968 D/SystemControl( 2996): [CPQControl]:GetHDRMode: mode is 32 05-01 18:32:13.968 D/SystemControl( 2996): [CPQControl]:Same signal,no need load! 05-01 18:32:13.969 D/AmlogicVideoDecoderAwesome( 3114): memset outputbuffer index=6 05-01 18:32:13.970 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.971 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.973 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.974 I/MiniDataSource(19784): we_received 1114112 bytes data 05-01 18:32:13.975 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.980 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.982 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.984 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.993 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:13.999 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.001 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.003 D/AmlogicVideoDecoderAwesome( 3114): memset outputbuffer index=7 05-01 18:32:14.003 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.006 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.008 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.010 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.012 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.014 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.017 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.020 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.023 I/MiniDataSource(19784): we_received 1212416 bytes data 05-01 18:32:14.024 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.026 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.029 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.031 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.035 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.041 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.043 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.044 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.046 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.052 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.055 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.057 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.059 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.060 I/MiniDataSource(19784): we_received 1310720 bytes data 05-01 18:32:14.060 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.062 D/Chronicle_0(19784): chronicle: start buffered at: 3673006971, 493.0 ms from birth, 394.0 ms from first bytes 05-01 18:32:14.062 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.067 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.069 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.072 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.077 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.079 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.080 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.082 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.084 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.087 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.090 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.095 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.098 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.100 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.103 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.105 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.109 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.110 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.112 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.114 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.144 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.148 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.153 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.155 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.208 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.210 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.213 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.214 I/MiniDataSource(19784): we_received 1409024 bytes data 05-01 18:32:14.215 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.219 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.220 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.231 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.233 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.241 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.243 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.245 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.247 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.275 D/TSFileSource(19784): readSize: 94000/94000 05-01 18:32:14.277 D/TSFileSource(19784): readSize: 74448/94000 05-01 18:32:14.346 I/MiniDataSource(19784): we_received 1507328 bytes data ^C 17:32:15\|jim@x1c-ubuntu:/wk/ambuild_9.0$ vod_reproduced.logcat (203,124 bytes)

(ALTech) SY Yoon 2019-06-18 19:01 developer ~0002739	It fixed

Date Modified	Username	Field	Change
2019-03-25 16:56	(ALTech) Jewoo Lee	New Issue
2019-03-25 16:56	(ALTech) Jewoo Lee	Status	new => assigned
2019-03-25 16:56	(ALTech) Jewoo Lee	Assigned To	=> (SW) Jacky Chiang
2019-03-25 16:56	(ALTech) Jewoo Lee	File Added: log_VOD.play.failure.txt
2019-03-25 16:56	(ALTech) Jewoo Lee	File Added: VID_20190325_173430.mp4
2019-03-26 20:33	(SW) Jacky Chiang	Project	T99X171.00 SKB Eagle => T99X171.00 SKB Eagle_Vendor_Issue
2019-03-26 20:33	(SW) Jacky Chiang	Category	SW Issue => General
2019-03-26 20:33	(SW) Jacky Chiang	Category	General => Player Issue
2019-03-26 20:33	(SW) Jacky Chiang	Description Updated
2019-03-26 20:33	(SW) Jacky Chiang	Steps to Reproduce Updated
2019-03-26 20:37	(SW) Jacky Chiang	Assigned To	(SW) Jacky Chiang => (Amlogic) Jingyu Xu
2019-03-27 17:00	(Amlogic) Jingyu Xu	Note Added: 0001747
2019-04-30 13:43	(SW) Jacky Chiang	Note Added: 0002317
2019-04-30 13:43	(SW) Jacky Chiang	Note Edited: 0002317
2019-04-30 14:06	(SW) Jacky Chiang	Note Edited: 0002317
2019-05-01 18:56	(SW) Jim Chen	Note Added: 0002347
2019-05-02 19:35	(SW) Jim Chen	Note Added: 0002359
2019-05-02 19:35	(SW) Jim Chen	File Added: 105_201.png
2019-05-02 19:37	(SW) Jim Chen	File Added: 105_201-2.png
2019-05-02 19:37	(SW) Jim Chen	Note Added: 0002360
2019-05-03 14:36	(SW) Jim Chen	Assigned To	(Amlogic) Jingyu Xu => (ALTech) Jewoo Lee
2019-05-03 14:58	(SW) Jim Chen	File Added: vod_reproduced.logcat
2019-05-03 14:58	(SW) Jim Chen	Note Added: 0002369
2019-06-18 19:01	(ALTech) SY Yoon	Note Added: 0002739
2019-06-18 19:01	(ALTech) SY Yoon	Status	assigned => closed
2019-06-18 19:01	(ALTech) SY Yoon	Resolution	open => fixed

View Issue Details

Users monitoring this issue

Activities

Issue History